{"level":"warn","ts":"2026-06-24T17:56:07.869354Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-24T17:56:07.869564Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.108:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.108:2380","--initial-cluster=instance=https://199.19.213.108:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.108:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.108: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-06-24T17:56:07.869755Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-24T17:56:07.86985Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.108:2380"]} {"level":"info","ts":"2026-06-24T17:56:07.87012Z","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-06-24T17:56:07.871183Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"]} {"level":"info","ts":"2026-06-24T17:56:07.871338Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.108:2380"],"listen-peer-urls":["https://199.19.213.108:2380"],"advertise-client-urls":["https://199.19.213.108:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.108: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-06-24T17:56:07.875658Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.008694ms"} {"level":"info","ts":"2026-06-24T17:56:07.8842Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e9047e95cdba2056","cluster-id":"51f5e218724a48a2"} {"level":"info","ts":"2026-06-24T17:56:07.884302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=()"} {"level":"info","ts":"2026-06-24T17:56:07.884337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became follower at term 0"} {"level":"info","ts":"2026-06-24T17:56:07.884357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e9047e95cdba2056 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-24T17:56:07.884379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became follower at term 1"} {"level":"info","ts":"2026-06-24T17:56:07.884474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=(16790684492610805846)"} {"level":"warn","ts":"2026-06-24T17:56:07.890992Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-24T17:56:07.892508Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-24T17:56:07.893609Z","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-06-24T17:56:07.895924Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e9047e95cdba2056","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-24T17:56:07.896079Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e9047e95cdba2056","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-24T17:56:07.896189Z","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-06-24T17:56:07.896777Z","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-06-24T17:56:07.8968Z","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-06-24T17:56:07.897109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=(16790684492610805846)"} {"level":"info","ts":"2026-06-24T17:56:07.897276Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"51f5e218724a48a2","local-member-id":"e9047e95cdba2056","added-peer-id":"e9047e95cdba2056","added-peer-peer-urls":["https://199.19.213.108:2380"]} {"level":"info","ts":"2026-06-24T17:56:07.900014Z","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-06-24T17:56:07.900212Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e9047e95cdba2056","initial-advertise-peer-urls":["https://199.19.213.108:2380"],"listen-peer-urls":["https://199.19.213.108:2380"],"advertise-client-urls":["https://199.19.213.108:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-24T17:56:07.90025Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-24T17:56:07.900323Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.108:2380"} {"level":"info","ts":"2026-06-24T17:56:07.900339Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.108:2380"} {"level":"info","ts":"2026-06-24T17:56:08.385595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 is starting a new election at term 1"} {"level":"info","ts":"2026-06-24T17:56:08.38576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-24T17:56:08.385832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 received MsgPreVoteResp from e9047e95cdba2056 at term 1"} {"level":"info","ts":"2026-06-24T17:56:08.385888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became candidate at term 2"} {"level":"info","ts":"2026-06-24T17:56:08.385932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 received MsgVoteResp from e9047e95cdba2056 at term 2"} {"level":"info","ts":"2026-06-24T17:56:08.385975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became leader at term 2"} {"level":"info","ts":"2026-06-24T17:56:08.386018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e9047e95cdba2056 elected leader e9047e95cdba2056 at term 2"} {"level":"info","ts":"2026-06-24T17:56:08.38695Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e9047e95cdba2056","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.108:2379]}","request-path":"/0/members/e9047e95cdba2056/attributes","cluster-id":"51f5e218724a48a2","publish-timeout":"7s"} {"level":"info","ts":"2026-06-24T17:56:08.387154Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T17:56:08.387343Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:56:08.387449Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-24T17:56:08.387495Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-24T17:56:08.387318Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T17:56:08.388609Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"51f5e218724a48a2","local-member-id":"e9047e95cdba2056","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:56:08.388707Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:56:08.388739Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:56:08.39152Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-24T17:56:08.39191Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.108:2379"} {"level":"warn","ts":"2026-06-24T17:56:10.249184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.942543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-06-24T17:56:10.249352Z","caller":"traceutil/trace.go:171","msg":"trace[1781058654] linearizableReadLoop","detail":"{readStateIndex:100; appliedIndex:99; }","duration":"300.9906ms","start":"2026-06-24T17:56:09.948336Z","end":"2026-06-24T17:56:10.249327Z","steps":["trace[1781058654] 'read index received' (duration: 86.011592ms)","trace[1781058654] 'applied index is now lower than readState.Index' (duration: 214.977038ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:56:10.249489Z","caller":"traceutil/trace.go:171","msg":"trace[382257219] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"314.967563ms","start":"2026-06-24T17:56:09.934445Z","end":"2026-06-24T17:56:10.249413Z","steps":["trace[382257219] 'process raft request' (duration: 99.971325ms)","trace[382257219] 'compare' (duration: 213.7763ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:56:10.249736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.216178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-24T17:56:10.249731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.359915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-24T17:56:10.249798Z","caller":"traceutil/trace.go:171","msg":"trace[291809049] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:95; }","duration":"185.31862ms","start":"2026-06-24T17:56:10.064456Z","end":"2026-06-24T17:56:10.249774Z","steps":["trace[291809049] 'agreement among raft nodes before linearized reading' (duration: 185.145677ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:10.24983Z","caller":"traceutil/trace.go:171","msg":"trace[1014325763] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:95; }","duration":"301.507038ms","start":"2026-06-24T17:56:09.948298Z","end":"2026-06-24T17:56:10.249805Z","steps":["trace[1014325763] 'agreement among raft nodes before linearized reading' (duration: 301.251614ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:10.249738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:09.934428Z","time spent":"315.183276ms","remote":"127.0.0.1:52790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:56:10.249893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:09.948283Z","time spent":"301.598359ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-06-24T17:56:10.556901Z","caller":"traceutil/trace.go:171","msg":"trace[547682885] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"302.770294ms","start":"2026-06-24T17:56:10.254084Z","end":"2026-06-24T17:56:10.556855Z","steps":["trace[547682885] 'process raft request' (duration: 302.630362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:10.557039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:10.25405Z","time spent":"302.927726ms","remote":"127.0.0.1:52884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T17:56:10.88512Z","caller":"traceutil/trace.go:171","msg":"trace[1401846004] linearizableReadLoop","detail":"{readStateIndex:103; appliedIndex:101; }","duration":"287.063087ms","start":"2026-06-24T17:56:10.598036Z","end":"2026-06-24T17:56:10.885099Z","steps":["trace[1401846004] 'read index received' (duration: 282.778952ms)","trace[1401846004] 'applied index is now lower than readState.Index' (duration: 4.283215ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:56:10.885309Z","caller":"traceutil/trace.go:171","msg":"trace[1815184419] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"629.147495ms","start":"2026-06-24T17:56:10.256132Z","end":"2026-06-24T17:56:10.88528Z","steps":["trace[1815184419] 'process raft request' (duration: 624.750808ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:10.885346Z","caller":"traceutil/trace.go:171","msg":"trace[1571955042] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"627.846815ms","start":"2026-06-24T17:56:10.257426Z","end":"2026-06-24T17:56:10.885272Z","steps":["trace[1571955042] 'process raft request' (duration: 627.625372ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:10.885514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.457823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-24T17:56:10.885905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:10.257411Z","time spent":"628.459644ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T17:56:10.885995Z","caller":"traceutil/trace.go:171","msg":"trace[322498424] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:98; }","duration":"287.99093ms","start":"2026-06-24T17:56:10.597987Z","end":"2026-06-24T17:56:10.885978Z","steps":["trace[322498424] 'agreement among raft nodes before linearized reading' (duration: 287.380661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:10.885641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.431781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-24T17:56:10.886261Z","caller":"traceutil/trace.go:171","msg":"trace[1457879585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:98; }","duration":"180.09805ms","start":"2026-06-24T17:56:10.706146Z","end":"2026-06-24T17:56:10.886244Z","steps":["trace[1457879585] 'agreement among raft nodes before linearized reading' (duration: 179.43371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:10.885649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.355361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-24T17:56:10.886406Z","caller":"traceutil/trace.go:171","msg":"trace[1250718811] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:98; }","duration":"288.159313ms","start":"2026-06-24T17:56:10.598237Z","end":"2026-06-24T17:56:10.886396Z","steps":["trace[1250718811] 'agreement among raft nodes before linearized reading' (duration: 287.329461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:10.886543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:10.256107Z","time spent":"629.618232ms","remote":"127.0.0.1:52790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:56:11.036892Z","caller":"traceutil/trace.go:171","msg":"trace[946961559] linearizableReadLoop","detail":"{readStateIndex:108; appliedIndex:107; }","duration":"135.932916ms","start":"2026-06-24T17:56:10.900936Z","end":"2026-06-24T17:56:11.036869Z","steps":["trace[946961559] 'read index received' (duration: 130.414574ms)","trace[946961559] 'applied index is now lower than readState.Index' (duration: 5.517342ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:56:11.03694Z","caller":"traceutil/trace.go:171","msg":"trace[825273924] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"137.222367ms","start":"2026-06-24T17:56:10.899684Z","end":"2026-06-24T17:56:11.036906Z","steps":["trace[825273924] 'process raft request' (duration: 131.627383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:11.037052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.092628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-24T17:56:11.0371Z","caller":"traceutil/trace.go:171","msg":"trace[1509906471] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:103; }","duration":"136.170999ms","start":"2026-06-24T17:56:10.900914Z","end":"2026-06-24T17:56:11.037085Z","steps":["trace[1509906471] 'agreement among raft nodes before linearized reading' (duration: 136.045928ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.32961Z","caller":"traceutil/trace.go:171","msg":"trace[684316932] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"363.401617ms","start":"2026-06-24T17:56:45.966182Z","end":"2026-06-24T17:56:46.329583Z","steps":["trace[684316932] 'process raft request' (duration: 363.236555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:46.329757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:45.966159Z","time spent":"363.525178ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:56:46.338001Z","caller":"traceutil/trace.go:171","msg":"trace[1739746156] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"370.27293ms","start":"2026-06-24T17:56:45.967707Z","end":"2026-06-24T17:56:46.33798Z","steps":["trace[1739746156] 'process raft request' (duration: 369.960846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:46.338114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:45.9677Z","time spent":"370.353721ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:56:46.338296Z","caller":"traceutil/trace.go:171","msg":"trace[2120216079] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:549; }","duration":"231.302636ms","start":"2026-06-24T17:56:46.106972Z","end":"2026-06-24T17:56:46.338275Z","steps":["trace[2120216079] 'read index received' (duration: 222.530494ms)","trace[2120216079] 'applied index is now lower than readState.Index' (duration: 8.771082ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:56:46.338395Z","caller":"traceutil/trace.go:171","msg":"trace[789729364] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"293.305883ms","start":"2026-06-24T17:56:46.045071Z","end":"2026-06-24T17:56:46.338377Z","steps":["trace[789729364] 'process raft request' (duration: 292.716306ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.338568Z","caller":"traceutil/trace.go:171","msg":"trace[190888916] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"246.934279ms","start":"2026-06-24T17:56:46.091613Z","end":"2026-06-24T17:56:46.338548Z","steps":["trace[190888916] 'process raft request' (duration: 246.459064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:46.338763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.74871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-24T17:56:46.338913Z","caller":"traceutil/trace.go:171","msg":"trace[1746939404] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"227.995891ms","start":"2026-06-24T17:56:46.110908Z","end":"2026-06-24T17:56:46.338904Z","steps":["trace[1746939404] 'process raft request' (duration: 227.258104ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.338872Z","caller":"traceutil/trace.go:171","msg":"trace[1100597946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"231.920652ms","start":"2026-06-24T17:56:46.106932Z","end":"2026-06-24T17:56:46.338852Z","steps":["trace[1100597946] 'agreement among raft nodes before linearized reading' (duration: 231.622289ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.339031Z","caller":"traceutil/trace.go:171","msg":"trace[164971372] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"276.432117ms","start":"2026-06-24T17:56:46.062593Z","end":"2026-06-24T17:56:46.339025Z","steps":["trace[164971372] 'process raft request' (duration: 275.351216ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.339097Z","caller":"traceutil/trace.go:171","msg":"trace[592230394] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"291.874017ms","start":"2026-06-24T17:56:46.047218Z","end":"2026-06-24T17:56:46.339092Z","steps":["trace[592230394] 'process raft request' (duration: 290.608864ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.339148Z","caller":"traceutil/trace.go:171","msg":"trace[35124062] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"291.405363ms","start":"2026-06-24T17:56:46.047736Z","end":"2026-06-24T17:56:46.339141Z","steps":["trace[35124062] 'process raft request' (duration: 290.12639ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.339205Z","caller":"traceutil/trace.go:171","msg":"trace[1075067551] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"279.130755ms","start":"2026-06-24T17:56:46.060069Z","end":"2026-06-24T17:56:46.339199Z","steps":["trace[1075067551] 'process raft request' (duration: 277.821152ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.339247Z","caller":"traceutil/trace.go:171","msg":"trace[1489699803] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"279.145436ms","start":"2026-06-24T17:56:46.060097Z","end":"2026-06-24T17:56:46.339243Z","steps":["trace[1489699803] 'process raft request' (duration: 277.820853ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.339294Z","caller":"traceutil/trace.go:171","msg":"trace[1923421358] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"138.524676ms","start":"2026-06-24T17:56:46.200765Z","end":"2026-06-24T17:56:46.33929Z","steps":["trace[1923421358] 'process raft request' (duration: 137.467636ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.339348Z","caller":"traceutil/trace.go:171","msg":"trace[1145276976] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"268.008429ms","start":"2026-06-24T17:56:46.071334Z","end":"2026-06-24T17:56:46.339342Z","steps":["trace[1145276976] 'process raft request' (duration: 266.634125ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.339388Z","caller":"traceutil/trace.go:171","msg":"trace[291439853] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"248.013009ms","start":"2026-06-24T17:56:46.091371Z","end":"2026-06-24T17:56:46.339384Z","steps":["trace[291439853] 'process raft request' (duration: 246.633255ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:46.338995Z","caller":"traceutil/trace.go:171","msg":"trace[157279237] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"247.12395ms","start":"2026-06-24T17:56:46.091859Z","end":"2026-06-24T17:56:46.338983Z","steps":["trace[157279237] 'process raft request' (duration: 246.253131ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:47.154268Z","caller":"traceutil/trace.go:171","msg":"trace[1248801681] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"140.161964ms","start":"2026-06-24T17:56:47.014074Z","end":"2026-06-24T17:56:47.154235Z","steps":["trace[1248801681] 'process raft request' (duration: 74.177789ms)","trace[1248801681] 'compare' (duration: 65.789323ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:56:47.175839Z","caller":"traceutil/trace.go:171","msg":"trace[352892628] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"144.840183ms","start":"2026-06-24T17:56:47.030959Z","end":"2026-06-24T17:56:47.175799Z","steps":["trace[352892628] 'process raft request' (duration: 144.54995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:47.96957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224657118679212,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T17:56:48.229434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.698227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T17:56:48.230025Z","caller":"traceutil/trace.go:171","msg":"trace[968631377] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"761.525105ms","start":"2026-06-24T17:56:47.468457Z","end":"2026-06-24T17:56:48.229982Z","steps":["trace[968631377] 'read index received' (duration: 600.284362ms)","trace[968631377] 'applied index is now lower than readState.Index' (duration: 161.239673ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:56:48.230117Z","caller":"traceutil/trace.go:171","msg":"trace[319690694] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"966.576932ms","start":"2026-06-24T17:56:47.263529Z","end":"2026-06-24T17:56:48.230106Z","steps":["trace[319690694] 'process raft request' (duration: 805.125007ms)","trace[319690694] 'compare' (duration: 160.314704ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:56:48.230239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.263508Z","time spent":"966.694253ms","remote":"127.0.0.1:52754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T17:56:48.230872Z","caller":"traceutil/trace.go:171","msg":"trace[1618707978] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"883.335088ms","start":"2026-06-24T17:56:47.347526Z","end":"2026-06-24T17:56:48.230861Z","steps":["trace[1618707978] 'process raft request' (duration: 882.129206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.230944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.347508Z","time spent":"883.396259ms","remote":"127.0.0.1:52754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:56:48.231098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.637476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-24T17:56:48.231134Z","caller":"traceutil/trace.go:171","msg":"trace[1735203756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"762.703127ms","start":"2026-06-24T17:56:47.468421Z","end":"2026-06-24T17:56:48.231124Z","steps":["trace[1735203756] 'agreement among raft nodes before linearized reading' (duration: 762.636196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.231163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.468374Z","time spent":"762.781617ms","remote":"127.0.0.1:52952","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":"warn","ts":"2026-06-24T17:56:48.23135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.011035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.231391Z","caller":"traceutil/trace.go:171","msg":"trace[1571473189] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:601; }","duration":"617.081636ms","start":"2026-06-24T17:56:47.614299Z","end":"2026-06-24T17:56:48.231381Z","steps":["trace[1571473189] 'agreement among raft nodes before linearized reading' (duration: 617.021955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.231422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.614279Z","time spent":"617.133847ms","remote":"127.0.0.1:39854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-06-24T17:56:48.232367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.044378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.232543Z","caller":"traceutil/trace.go:171","msg":"trace[239627800] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:601; }","duration":"407.174339ms","start":"2026-06-24T17:56:47.825284Z","end":"2026-06-24T17:56:48.232459Z","steps":["trace[239627800] 'agreement among raft nodes before linearized reading' (duration: 406.967767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.23265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.825268Z","time spent":"407.342581ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:56:48.232823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.142458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.232872Z","caller":"traceutil/trace.go:171","msg":"trace[507687072] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:601; }","duration":"459.226548ms","start":"2026-06-24T17:56:47.773635Z","end":"2026-06-24T17:56:48.232861Z","steps":["trace[507687072] 'agreement among raft nodes before linearized reading' (duration: 459.046626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.233027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.773622Z","time spent":"459.389991ms","remote":"127.0.0.1:39914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-06-24T17:56:48.233376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.182858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.233417Z","caller":"traceutil/trace.go:171","msg":"trace[946633300] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:601; }","duration":"460.255929ms","start":"2026-06-24T17:56:47.773151Z","end":"2026-06-24T17:56:48.233407Z","steps":["trace[946633300] 'agreement among raft nodes before linearized reading' (duration: 460.190938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.233452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.773136Z","time spent":"460.308389ms","remote":"127.0.0.1:39914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:56:48.233676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.540711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.233718Z","caller":"traceutil/trace.go:171","msg":"trace[215245514] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:601; }","duration":"460.600303ms","start":"2026-06-24T17:56:47.773108Z","end":"2026-06-24T17:56:48.233709Z","steps":["trace[215245514] 'agreement among raft nodes before linearized reading' (duration: 460.538662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.233806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.773097Z","time spent":"460.645213ms","remote":"127.0.0.1:39914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-06-24T17:56:48.234034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.861734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.23408Z","caller":"traceutil/trace.go:171","msg":"trace[480120625] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:601; }","duration":"485.939655ms","start":"2026-06-24T17:56:47.748128Z","end":"2026-06-24T17:56:48.234068Z","steps":["trace[480120625] 'agreement among raft nodes before linearized reading' (duration: 485.846284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.234126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.748111Z","time spent":"486.003876ms","remote":"127.0.0.1:39898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"warn","ts":"2026-06-24T17:56:48.23436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.805504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.234398Z","caller":"traceutil/trace.go:171","msg":"trace[6115981] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:601; }","duration":"486.852166ms","start":"2026-06-24T17:56:47.747537Z","end":"2026-06-24T17:56:48.234389Z","steps":["trace[6115981] 'agreement among raft nodes before linearized reading' (duration: 486.793664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.234427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.747534Z","time spent":"486.885226ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:56:48.234593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.049977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.234628Z","caller":"traceutil/trace.go:171","msg":"trace[1959030586] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:601; }","duration":"487.119578ms","start":"2026-06-24T17:56:47.747498Z","end":"2026-06-24T17:56:48.234617Z","steps":["trace[1959030586] 'agreement among raft nodes before linearized reading' (duration: 487.063787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.23466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.747483Z","time spent":"487.169358ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-06-24T17:56:48.234816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.123925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.234848Z","caller":"traceutil/trace.go:171","msg":"trace[1968018690] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:601; }","duration":"530.171465ms","start":"2026-06-24T17:56:47.704668Z","end":"2026-06-24T17:56:48.234839Z","steps":["trace[1968018690] 'agreement among raft nodes before linearized reading' (duration: 530.123585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.234878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.704663Z","time spent":"530.207875ms","remote":"127.0.0.1:39898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:56:48.234912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.38853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.234963Z","caller":"traceutil/trace.go:171","msg":"trace[1535101063] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:601; }","duration":"211.47561ms","start":"2026-06-24T17:56:48.023477Z","end":"2026-06-24T17:56:48.234952Z","steps":["trace[1535101063] 'agreement among raft nodes before linearized reading' (duration: 211.378499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.234977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.322716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.234998Z","caller":"traceutil/trace.go:171","msg":"trace[1888332076] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:601; }","duration":"530.370677ms","start":"2026-06-24T17:56:47.70462Z","end":"2026-06-24T17:56:48.234991Z","steps":["trace[1888332076] 'agreement among raft nodes before linearized reading' (duration: 530.339956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.235023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.7046Z","time spent":"530.417228ms","remote":"127.0.0.1:39898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-06-24T17:56:48.235082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.617051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.235114Z","caller":"traceutil/trace.go:171","msg":"trace[1627402744] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:601; }","duration":"211.681023ms","start":"2026-06-24T17:56:48.023425Z","end":"2026-06-24T17:56:48.235106Z","steps":["trace[1627402744] 'agreement among raft nodes before linearized reading' (duration: 211.634012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.23511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.491674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.235157Z","caller":"traceutil/trace.go:171","msg":"trace[2097121521] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:601; }","duration":"570.538244ms","start":"2026-06-24T17:56:47.664612Z","end":"2026-06-24T17:56:48.235151Z","steps":["trace[2097121521] 'agreement among raft nodes before linearized reading' (duration: 570.478674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.235183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.664591Z","time spent":"570.586434ms","remote":"127.0.0.1:39886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-06-24T17:56:48.235236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.05914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.235263Z","caller":"traceutil/trace.go:171","msg":"trace[971441895] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:601; }","duration":"387.13889ms","start":"2026-06-24T17:56:47.848115Z","end":"2026-06-24T17:56:48.235254Z","steps":["trace[971441895] 'agreement among raft nodes before linearized reading' (duration: 387.0983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.23529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.848106Z","time spent":"387.17445ms","remote":"127.0.0.1:39930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-06-24T17:56:48.235424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.701896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.235452Z","caller":"traceutil/trace.go:171","msg":"trace[1242662755] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:601; }","duration":"571.758666ms","start":"2026-06-24T17:56:47.663686Z","end":"2026-06-24T17:56:48.235445Z","steps":["trace[1242662755] 'agreement among raft nodes before linearized reading' (duration: 571.712876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.235454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.0382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T17:56:48.235475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.663669Z","time spent":"571.800406ms","remote":"127.0.0.1:39886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"info","ts":"2026-06-24T17:56:48.23548Z","caller":"traceutil/trace.go:171","msg":"trace[1380457031] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:601; }","duration":"388.07895ms","start":"2026-06-24T17:56:47.847394Z","end":"2026-06-24T17:56:48.235473Z","steps":["trace[1380457031] 'agreement among raft nodes before linearized reading' (duration: 388.04525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.235505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.847382Z","time spent":"388.116091ms","remote":"127.0.0.1:39930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-06-24T17:56:48.235593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.756426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.235616Z","caller":"traceutil/trace.go:171","msg":"trace[658274127] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:601; }","duration":"409.797716ms","start":"2026-06-24T17:56:47.825813Z","end":"2026-06-24T17:56:48.235611Z","steps":["trace[658274127] 'agreement among raft nodes before linearized reading' (duration: 409.763335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.235642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.959308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T17:56:48.23567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.30079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.235698Z","caller":"traceutil/trace.go:171","msg":"trace[17300837] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:601; }","duration":"572.010288ms","start":"2026-06-24T17:56:47.663651Z","end":"2026-06-24T17:56:48.235661Z","steps":["trace[17300837] 'agreement among raft nodes before linearized reading' (duration: 571.952738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.235726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.663637Z","time spent":"572.082349ms","remote":"127.0.0.1:39886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:56:48.235813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.817766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.235835Z","caller":"traceutil/trace.go:171","msg":"trace[65619839] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:601; }","duration":"595.855986ms","start":"2026-06-24T17:56:47.639974Z","end":"2026-06-24T17:56:48.23583Z","steps":["trace[65619839] 'agreement among raft nodes before linearized reading' (duration: 595.822616ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.235855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.639967Z","time spent":"595.883616ms","remote":"127.0.0.1:39870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-06-24T17:56:48.235952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.439202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.235974Z","caller":"traceutil/trace.go:171","msg":"trace[688688140] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:601; }","duration":"596.471802ms","start":"2026-06-24T17:56:47.639497Z","end":"2026-06-24T17:56:48.235969Z","steps":["trace[688688140] 'agreement among raft nodes before linearized reading' (duration: 596.437102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.235997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.639492Z","time spent":"596.498443ms","remote":"127.0.0.1:39870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:56:48.236078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.942627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.236099Z","caller":"traceutil/trace.go:171","msg":"trace[889081672] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:601; }","duration":"596.986408ms","start":"2026-06-24T17:56:47.639107Z","end":"2026-06-24T17:56:48.236094Z","steps":["trace[889081672] 'agreement among raft nodes before linearized reading' (duration: 596.955927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.236119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.639091Z","time spent":"597.023508ms","remote":"127.0.0.1:39870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-06-24T17:56:48.236196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.929897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.236237Z","caller":"traceutil/trace.go:171","msg":"trace[1413746934] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:601; }","duration":"620.979637ms","start":"2026-06-24T17:56:47.615232Z","end":"2026-06-24T17:56:48.236212Z","steps":["trace[1413746934] 'agreement among raft nodes before linearized reading' (duration: 620.947666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.236258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.615221Z","time spent":"621.031857ms","remote":"127.0.0.1:39854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"warn","ts":"2026-06-24T17:56:48.23634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.974677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.236364Z","caller":"traceutil/trace.go:171","msg":"trace[715448081] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:601; }","duration":"622.010597ms","start":"2026-06-24T17:56:47.614346Z","end":"2026-06-24T17:56:48.236356Z","steps":["trace[715448081] 'agreement among raft nodes before linearized reading' (duration: 621.978747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.236403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.614341Z","time spent":"622.055708ms","remote":"127.0.0.1:39854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:56:48.235425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.869229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:56:48.236505Z","caller":"traceutil/trace.go:171","msg":"trace[841450463] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:601; }","duration":"388.9638ms","start":"2026-06-24T17:56:47.847533Z","end":"2026-06-24T17:56:48.236497Z","steps":["trace[841450463] 'agreement among raft nodes before linearized reading' (duration: 387.869839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.236536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.847529Z","time spent":"388.99915ms","remote":"127.0.0.1:39930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:56:48.235645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.825804Z","time spent":"409.835356ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-06-24T17:56:48.236743Z","caller":"traceutil/trace.go:171","msg":"trace[2114150469] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:601; }","duration":"410.361223ms","start":"2026-06-24T17:56:47.825326Z","end":"2026-06-24T17:56:48.235687Z","steps":["trace[2114150469] 'agreement among raft nodes before linearized reading' (duration: 410.330242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:48.236771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:47.825306Z","time spent":"411.457453ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-06-24T17:56:52.990391Z","caller":"traceutil/trace.go:171","msg":"trace[1176517417] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"290.26405ms","start":"2026-06-24T17:56:52.700097Z","end":"2026-06-24T17:56:52.990361Z","steps":["trace[1176517417] 'process raft request' (duration: 290.074338ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:56:53.144087Z","caller":"traceutil/trace.go:171","msg":"trace[898309365] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"434.674736ms","start":"2026-06-24T17:56:52.709389Z","end":"2026-06-24T17:56:53.144064Z","steps":["trace[898309365] 'process raft request' (duration: 434.236951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:53.144218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:52.709353Z","time spent":"434.799667ms","remote":"127.0.0.1:52802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:56:53.780478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224657118679383,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T17:56:54.209857Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.011288139s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T17:56:54.241242Z","caller":"traceutil/trace.go:171","msg":"trace[705940012] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"961.143155ms","start":"2026-06-24T17:56:53.280067Z","end":"2026-06-24T17:56:54.24121Z","steps":["trace[705940012] 'read index received' (duration: 929.926974ms)","trace[705940012] 'applied index is now lower than readState.Index' (duration: 31.214431ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:56:54.241258Z","caller":"traceutil/trace.go:171","msg":"trace[182431550] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.042701931s","start":"2026-06-24T17:56:53.19851Z","end":"2026-06-24T17:56:54.241212Z","steps":["trace[182431550] 'process raft request' (duration: 1.011587021s)","trace[182431550] 'compare' (duration: 30.493394ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:56:54.241405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.327167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T17:56:54.241406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:53.198493Z","time spent":"1.042841782s","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T17:56:54.241439Z","caller":"traceutil/trace.go:171","msg":"trace[1077598118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"961.418458ms","start":"2026-06-24T17:56:53.280009Z","end":"2026-06-24T17:56:54.241428Z","steps":["trace[1077598118] 'agreement among raft nodes before linearized reading' (duration: 961.305217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:56:54.241473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:56:53.279975Z","time spent":"961.490978ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T17:56:56.030686Z","caller":"traceutil/trace.go:171","msg":"trace[1494691877] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"121.177298ms","start":"2026-06-24T17:56:55.909486Z","end":"2026-06-24T17:56:56.030663Z","steps":["trace[1494691877] 'process raft request' (duration: 121.066747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:09.506806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.882501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:57:09.506893Z","caller":"traceutil/trace.go:171","msg":"trace[1573636024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"227.273078ms","start":"2026-06-24T17:57:09.2796Z","end":"2026-06-24T17:57:09.506873Z","steps":["trace[1573636024] 'range keys from in-memory index tree' (duration: 226.807419ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:57:09.691806Z","caller":"traceutil/trace.go:171","msg":"trace[1832911053] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"112.888758ms","start":"2026-06-24T17:57:09.578883Z","end":"2026-06-24T17:57:09.691772Z","steps":["trace[1832911053] 'process raft request' (duration: 112.723511ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:57:37.538954Z","caller":"traceutil/trace.go:171","msg":"trace[151392729] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"142.298399ms","start":"2026-06-24T17:57:37.396621Z","end":"2026-06-24T17:57:37.538919Z","steps":["trace[151392729] 'process raft request' (duration: 142.106603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:57:54.713914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.842921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:57:54.714047Z","caller":"traceutil/trace.go:171","msg":"trace[2026972832] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1340; }","duration":"167.040407ms","start":"2026-06-24T17:57:54.546985Z","end":"2026-06-24T17:57:54.714026Z","steps":["trace[2026972832] 'count revisions from in-memory index tree' (duration: 166.758569ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:00.164173Z","caller":"traceutil/trace.go:171","msg":"trace[927851682] linearizableReadLoop","detail":"{readStateIndex:1502; appliedIndex:1501; }","duration":"132.021363ms","start":"2026-06-24T17:58:00.032119Z","end":"2026-06-24T17:58:00.16414Z","steps":["trace[927851682] 'read index received' (duration: 131.754066ms)","trace[927851682] 'applied index is now lower than readState.Index' (duration: 266.667µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:58:00.164322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.181776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:58:00.164346Z","caller":"traceutil/trace.go:171","msg":"trace[812344210] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1458; }","duration":"132.253528ms","start":"2026-06-24T17:58:00.032085Z","end":"2026-06-24T17:58:00.164339Z","steps":["trace[812344210] 'agreement among raft nodes before linearized reading' (duration: 132.174606ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:00.164317Z","caller":"traceutil/trace.go:171","msg":"trace[135403951] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"136.888936ms","start":"2026-06-24T17:58:00.027382Z","end":"2026-06-24T17:58:00.164271Z","steps":["trace[135403951] 'process raft request' (duration: 136.552597ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:01.320749Z","caller":"traceutil/trace.go:171","msg":"trace[1639749505] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"140.486797ms","start":"2026-06-24T17:58:01.180197Z","end":"2026-06-24T17:58:01.320684Z","steps":["trace[1639749505] 'process raft request' (duration: 140.150559ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:23.701535Z","caller":"traceutil/trace.go:171","msg":"trace[1134760204] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"183.024611ms","start":"2026-06-24T17:58:23.518478Z","end":"2026-06-24T17:58:23.701502Z","steps":["trace[1134760204] 'process raft request' (duration: 182.687984ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:23.701806Z","caller":"traceutil/trace.go:171","msg":"trace[1809670293] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"180.916306ms","start":"2026-06-24T17:58:23.520874Z","end":"2026-06-24T17:58:23.70179Z","steps":["trace[1809670293] 'process raft request' (duration: 180.525768ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:23.701972Z","caller":"traceutil/trace.go:171","msg":"trace[318937319] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"181.020529ms","start":"2026-06-24T17:58:23.520922Z","end":"2026-06-24T17:58:23.701943Z","steps":["trace[318937319] 'process raft request' (duration: 180.809664ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:23.757014Z","caller":"traceutil/trace.go:171","msg":"trace[891982141] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"145.580218ms","start":"2026-06-24T17:58:23.611402Z","end":"2026-06-24T17:58:23.756982Z","steps":["trace[891982141] 'process raft request' (duration: 145.363224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:23.757942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.774458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/default\" ","response":"range_response_count:1 size:175"} {"level":"info","ts":"2026-06-24T17:58:23.758019Z","caller":"traceutil/trace.go:171","msg":"trace[1139650850] range","detail":"{range_begin:/registry/serviceaccounts/openstack/default; range_end:; response_count:1; response_revision:1824; }","duration":"100.90649ms","start":"2026-06-24T17:58:23.657097Z","end":"2026-06-24T17:58:23.758003Z","steps":["trace[1139650850] 'agreement among raft nodes before linearized reading' (duration: 99.835707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:24.073656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.521023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T17:58:24.073829Z","caller":"traceutil/trace.go:171","msg":"trace[1626806079] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"177.605916ms","start":"2026-06-24T17:58:23.896199Z","end":"2026-06-24T17:58:24.073805Z","steps":["trace[1626806079] 'process raft request' (duration: 51.696836ms)","trace[1626806079] 'compare' (duration: 125.378809ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:58:24.07395Z","caller":"traceutil/trace.go:171","msg":"trace[666907580] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"177.432613ms","start":"2026-06-24T17:58:23.8965Z","end":"2026-06-24T17:58:24.073933Z","steps":["trace[666907580] 'process raft request' (duration: 177.237369ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:24.073959Z","caller":"traceutil/trace.go:171","msg":"trace[1637718814] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"175.765589ms","start":"2026-06-24T17:58:23.898156Z","end":"2026-06-24T17:58:24.073921Z","steps":["trace[1637718814] 'process raft request' (duration: 175.643586ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:24.074045Z","caller":"traceutil/trace.go:171","msg":"trace[1571232567] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1905; }","duration":"176.563145ms","start":"2026-06-24T17:58:23.89745Z","end":"2026-06-24T17:58:24.074013Z","steps":["trace[1571232567] 'read index received' (duration: 4.415454ms)","trace[1571232567] 'applied index is now lower than readState.Index' (duration: 172.13708ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:58:24.074097Z","caller":"traceutil/trace.go:171","msg":"trace[86995475] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"168.407382ms","start":"2026-06-24T17:58:23.905675Z","end":"2026-06-24T17:58:24.074083Z","steps":["trace[86995475] 'process raft request' (duration: 168.216138ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:24.074117Z","caller":"traceutil/trace.go:171","msg":"trace[2118518874] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"153.287062ms","start":"2026-06-24T17:58:23.920805Z","end":"2026-06-24T17:58:24.074092Z","steps":["trace[2118518874] 'process raft request' (duration: 153.234571ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:24.074152Z","caller":"traceutil/trace.go:171","msg":"trace[1665530751] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"160.80915ms","start":"2026-06-24T17:58:23.91333Z","end":"2026-06-24T17:58:24.074139Z","steps":["trace[1665530751] 'process raft request' (duration: 160.620276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:24.074279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.81965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:100525"} {"level":"info","ts":"2026-06-24T17:58:24.074324Z","caller":"traceutil/trace.go:171","msg":"trace[1403159018] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1842; }","duration":"176.898102ms","start":"2026-06-24T17:58:23.897412Z","end":"2026-06-24T17:58:24.07431Z","steps":["trace[1403159018] 'agreement among raft nodes before linearized reading' (duration: 176.668947ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:24.093875Z","caller":"traceutil/trace.go:171","msg":"trace[785210040] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"106.481655ms","start":"2026-06-24T17:58:23.987369Z","end":"2026-06-24T17:58:24.09385Z","steps":["trace[785210040] 'process raft request' (duration: 106.283861ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:24.221517Z","caller":"traceutil/trace.go:171","msg":"trace[1730444580] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"101.753734ms","start":"2026-06-24T17:58:24.11973Z","end":"2026-06-24T17:58:24.221484Z","steps":["trace[1730444580] 'process raft request' (duration: 90.538208ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:24.224158Z","caller":"traceutil/trace.go:171","msg":"trace[2078191727] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1918; }","duration":"100.191751ms","start":"2026-06-24T17:58:24.123939Z","end":"2026-06-24T17:58:24.224131Z","steps":["trace[2078191727] 'read index received' (duration: 20.87126ms)","trace[2078191727] 'applied index is now lower than readState.Index' (duration: 79.316771ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:58:24.224377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.410996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:58:24.224446Z","caller":"traceutil/trace.go:171","msg":"trace[1630009616] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:1850; }","duration":"100.514228ms","start":"2026-06-24T17:58:24.12392Z","end":"2026-06-24T17:58:24.224434Z","steps":["trace[1630009616] 'agreement among raft nodes before linearized reading' (duration: 100.359954ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:27.243839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.514529ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-24T17:58:27.244301Z","caller":"traceutil/trace.go:171","msg":"trace[1546708116] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"166.775314ms","start":"2026-06-24T17:58:27.077495Z","end":"2026-06-24T17:58:27.24427Z","steps":["trace[1546708116] 'process raft request' (duration: 166.491148ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:27.244718Z","caller":"traceutil/trace.go:171","msg":"trace[1274071197] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"167.150021ms","start":"2026-06-24T17:58:27.077535Z","end":"2026-06-24T17:58:27.244685Z","steps":["trace[1274071197] 'process raft request' (duration: 166.56944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:27.995388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.835938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T17:58:27.995576Z","caller":"traceutil/trace.go:171","msg":"trace[992247636] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1892; }","duration":"373.066662ms","start":"2026-06-24T17:58:27.622479Z","end":"2026-06-24T17:58:27.995545Z","steps":["trace[992247636] 'count revisions from in-memory index tree' (duration: 372.747986ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:27.995676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:27.622461Z","time spent":"373.196015ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:58:27.995812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.705538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:58:27.995913Z","caller":"traceutil/trace.go:171","msg":"trace[1268839310] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1892; }","duration":"593.865871ms","start":"2026-06-24T17:58:27.402021Z","end":"2026-06-24T17:58:27.995886Z","steps":["trace[1268839310] 'count revisions from in-memory index tree' (duration: 593.589536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:27.995971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:27.401997Z","time spent":"593.961763ms","remote":"127.0.0.1:34126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:58:27.996032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.742822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T17:58:27.996221Z","caller":"traceutil/trace.go:171","msg":"trace[1618204384] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1892; }","duration":"228.087629ms","start":"2026-06-24T17:58:27.768065Z","end":"2026-06-24T17:58:27.996152Z","steps":["trace[1618204384] 'count revisions from in-memory index tree' (duration: 227.540008ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:28.344646Z","caller":"traceutil/trace.go:171","msg":"trace[1226590355] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"565.207553ms","start":"2026-06-24T17:58:27.779416Z","end":"2026-06-24T17:58:28.344624Z","steps":["trace[1226590355] 'process raft request' (duration: 565.074001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:28.344793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:27.779379Z","time spent":"565.335616ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:58:28.436894Z","caller":"traceutil/trace.go:171","msg":"trace[869431912] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1965; }","duration":"495.46215ms","start":"2026-06-24T17:58:27.941409Z","end":"2026-06-24T17:58:28.436871Z","steps":["trace[869431912] 'read index received' (duration: 403.141498ms)","trace[869431912] 'applied index is now lower than readState.Index' (duration: 92.319612ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:58:28.437247Z","caller":"traceutil/trace.go:171","msg":"trace[68026451] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"626.909845ms","start":"2026-06-24T17:58:27.810322Z","end":"2026-06-24T17:58:28.437232Z","steps":["trace[68026451] 'process raft request' (duration: 615.582792ms)","trace[68026451] 'compare' (duration: 10.782131ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:58:28.437405Z","caller":"traceutil/trace.go:171","msg":"trace[1347536248] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"337.699936ms","start":"2026-06-24T17:58:28.099667Z","end":"2026-06-24T17:58:28.437367Z","steps":["trace[1347536248] 'process raft request' (duration: 337.124594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:28.437429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.990572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T17:58:28.43743Z","caller":"traceutil/trace.go:171","msg":"trace[1913251946] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"328.869986ms","start":"2026-06-24T17:58:28.108531Z","end":"2026-06-24T17:58:28.437401Z","steps":["trace[1913251946] 'process raft request' (duration: 328.304304ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:28.437514Z","caller":"traceutil/trace.go:171","msg":"trace[651362683] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1896; }","duration":"496.122784ms","start":"2026-06-24T17:58:27.94137Z","end":"2026-06-24T17:58:28.437493Z","steps":["trace[651362683] 'agreement among raft nodes before linearized reading' (duration: 495.91841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:28.437574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.732744ms","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":"warn","ts":"2026-06-24T17:58:28.437571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:27.94135Z","time spent":"496.211206ms","remote":"127.0.0.1:52802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":20,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:58:28.437579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:28.099644Z","time spent":"337.8408ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:58:28.437616Z","caller":"traceutil/trace.go:171","msg":"trace[1922840966] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1896; }","duration":"115.814796ms","start":"2026-06-24T17:58:28.321787Z","end":"2026-06-24T17:58:28.437602Z","steps":["trace[1922840966] 'agreement among raft nodes before linearized reading' (duration: 115.701844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:28.437611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:28.108506Z","time spent":"329.018828ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:58:28.437633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.608889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:58:28.437693Z","caller":"traceutil/trace.go:171","msg":"trace[230398174] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1896; }","duration":"131.678921ms","start":"2026-06-24T17:58:28.306002Z","end":"2026-06-24T17:58:28.437681Z","steps":["trace[230398174] 'agreement among raft nodes before linearized reading' (duration: 131.561148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:28.437464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.690825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:58:28.437751Z","caller":"traceutil/trace.go:171","msg":"trace[463823823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"158.001961ms","start":"2026-06-24T17:58:28.279737Z","end":"2026-06-24T17:58:28.437739Z","steps":["trace[463823823] 'agreement among raft nodes before linearized reading' (duration: 157.690935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:28.437786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.965129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fefd6556-d6d8-41b7-b455-9aa16cde7356\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-06-24T17:58:28.437872Z","caller":"traceutil/trace.go:171","msg":"trace[333846954] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fefd6556-d6d8-41b7-b455-9aa16cde7356; range_end:; response_count:1; response_revision:1896; }","duration":"118.093032ms","start":"2026-06-24T17:58:28.319762Z","end":"2026-06-24T17:58:28.437855Z","steps":["trace[333846954] 'agreement among raft nodes before linearized reading' (duration: 117.829577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:28.437967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.961766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-24T17:58:28.438009Z","caller":"traceutil/trace.go:171","msg":"trace[1078438747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1896; }","duration":"151.022757ms","start":"2026-06-24T17:58:28.286976Z","end":"2026-06-24T17:58:28.437998Z","steps":["trace[1078438747] 'agreement among raft nodes before linearized reading' (duration: 150.897974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:28.43736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:27.81029Z","time spent":"626.993767ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:58:29.415067Z","caller":"traceutil/trace.go:171","msg":"trace[1760366741] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"664.912993ms","start":"2026-06-24T17:58:28.750128Z","end":"2026-06-24T17:58:29.415041Z","steps":["trace[1760366741] 'process raft request' (duration: 664.634417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:29.415339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:28.750109Z","time spent":"665.030686ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:58:29.415961Z","caller":"traceutil/trace.go:171","msg":"trace[1631806743] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1970; }","duration":"384.624967ms","start":"2026-06-24T17:58:29.031321Z","end":"2026-06-24T17:58:29.415946Z","steps":["trace[1631806743] 'read index received' (duration: 383.436933ms)","trace[1631806743] 'applied index is now lower than readState.Index' (duration: 1.186194ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:58:29.416048Z","caller":"traceutil/trace.go:171","msg":"trace[483731393] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"638.387098ms","start":"2026-06-24T17:58:28.777652Z","end":"2026-06-24T17:58:29.416039Z","steps":["trace[483731393] 'process raft request' (duration: 638.226675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:29.41612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:28.777625Z","time spent":"638.44952ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:58:29.416428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.866896ms","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-06-24T17:58:29.416508Z","caller":"traceutil/trace.go:171","msg":"trace[258442907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1899; }","duration":"340.021739ms","start":"2026-06-24T17:58:29.076468Z","end":"2026-06-24T17:58:29.41649Z","steps":["trace[258442907] 'agreement among raft nodes before linearized reading' (duration: 339.783054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:29.416548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:29.076446Z","time spent":"340.09287ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-24T17:58:29.416565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.88667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-24T17:58:29.416605Z","caller":"traceutil/trace.go:171","msg":"trace[871144915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1899; }","duration":"164.956892ms","start":"2026-06-24T17:58:29.251636Z","end":"2026-06-24T17:58:29.416593Z","steps":["trace[871144915] 'agreement among raft nodes before linearized reading' (duration: 164.828859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:29.416635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.307921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-24T17:58:29.416705Z","caller":"traceutil/trace.go:171","msg":"trace[1069949328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1899; }","duration":"385.426044ms","start":"2026-06-24T17:58:29.031262Z","end":"2026-06-24T17:58:29.416688Z","steps":["trace[1069949328] 'agreement among raft nodes before linearized reading' (duration: 385.196699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:29.417033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:29.031242Z","time spent":"385.781011ms","remote":"127.0.0.1:52952","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":"warn","ts":"2026-06-24T17:58:29.417459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.141385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-24T17:58:29.417514Z","caller":"traceutil/trace.go:171","msg":"trace[1652701401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1899; }","duration":"165.863951ms","start":"2026-06-24T17:58:29.251636Z","end":"2026-06-24T17:58:29.4175Z","steps":["trace[1652701401] 'agreement among raft nodes before linearized reading' (duration: 164.802969ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:34.613028Z","caller":"traceutil/trace.go:171","msg":"trace[2086668680] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"149.394599ms","start":"2026-06-24T17:58:34.463616Z","end":"2026-06-24T17:58:34.613011Z","steps":["trace[2086668680] 'process raft request' (duration: 147.828568ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:34.61313Z","caller":"traceutil/trace.go:171","msg":"trace[713402734] linearizableReadLoop","detail":"{readStateIndex:2026; appliedIndex:2024; }","duration":"144.223667ms","start":"2026-06-24T17:58:34.468895Z","end":"2026-06-24T17:58:34.613119Z","steps":["trace[713402734] 'read index received' (duration: 142.500923ms)","trace[713402734] 'applied index is now lower than readState.Index' (duration: 1.722244ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:58:34.613248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.18548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T17:58:34.613247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.344489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-24T17:58:34.613254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.043675ms","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-06-24T17:58:34.613275Z","caller":"traceutil/trace.go:171","msg":"trace[1784466282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1951; }","duration":"144.39092ms","start":"2026-06-24T17:58:34.468877Z","end":"2026-06-24T17:58:34.613268Z","steps":["trace[1784466282] 'agreement among raft nodes before linearized reading' (duration: 144.304088ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:34.613274Z","caller":"traceutil/trace.go:171","msg":"trace[1071854175] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1951; }","duration":"130.241041ms","start":"2026-06-24T17:58:34.483025Z","end":"2026-06-24T17:58:34.613266Z","steps":["trace[1071854175] 'agreement among raft nodes before linearized reading' (duration: 130.164199ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:34.613285Z","caller":"traceutil/trace.go:171","msg":"trace[2004617873] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1951; }","duration":"136.097206ms","start":"2026-06-24T17:58:34.47718Z","end":"2026-06-24T17:58:34.613277Z","steps":["trace[2004617873] 'agreement among raft nodes before linearized reading' (duration: 136.001174ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:34.61327Z","caller":"traceutil/trace.go:171","msg":"trace[1711845762] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"144.37066ms","start":"2026-06-24T17:58:34.468798Z","end":"2026-06-24T17:58:34.613169Z","steps":["trace[1711845762] 'process raft request' (duration: 144.184766ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:40.660749Z","caller":"traceutil/trace.go:171","msg":"trace[1194941403] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"168.843524ms","start":"2026-06-24T17:58:40.491831Z","end":"2026-06-24T17:58:40.660675Z","steps":["trace[1194941403] 'process raft request' (duration: 167.837315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:40.831416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.565376ms","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-06-24T17:58:40.831499Z","caller":"traceutil/trace.go:171","msg":"trace[503804511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1996; }","duration":"192.658298ms","start":"2026-06-24T17:58:40.638819Z","end":"2026-06-24T17:58:40.831477Z","steps":["trace[503804511] 'agreement among raft nodes before linearized reading' (duration: 51.621923ms)","trace[503804511] 'range keys from in-memory index tree' (duration: 140.838521ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:58:40.831766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.554159ms","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-06-24T17:58:40.831812Z","caller":"traceutil/trace.go:171","msg":"trace[326617782] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1996; }","duration":"178.628261ms","start":"2026-06-24T17:58:40.653165Z","end":"2026-06-24T17:58:40.831793Z","steps":["trace[326617782] 'agreement among raft nodes before linearized reading' (duration: 37.304461ms)","trace[326617782] 'range keys from in-memory index tree' (duration: 141.219348ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:58:40.832142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.448953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-5wqrx\" ","response":"range_response_count:1 size:5855"} {"level":"info","ts":"2026-06-24T17:58:40.832245Z","caller":"traceutil/trace.go:171","msg":"trace[523713682] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-5wqrx; range_end:; response_count:1; response_revision:1996; }","duration":"131.601796ms","start":"2026-06-24T17:58:40.700613Z","end":"2026-06-24T17:58:40.832215Z","steps":["trace[523713682] 'range keys from in-memory index tree' (duration: 131.142497ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:41.412943Z","caller":"traceutil/trace.go:171","msg":"trace[483102128] linearizableReadLoop","detail":"{readStateIndex:2074; appliedIndex:2073; }","duration":"133.60902ms","start":"2026-06-24T17:58:41.279311Z","end":"2026-06-24T17:58:41.41292Z","steps":["trace[483102128] 'read index received' (duration: 129.619963ms)","trace[483102128] 'applied index is now lower than readState.Index' (duration: 3.988097ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:58:41.413204Z","caller":"traceutil/trace.go:171","msg":"trace[1704116788] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"570.895324ms","start":"2026-06-24T17:58:40.842277Z","end":"2026-06-24T17:58:41.413172Z","steps":["trace[1704116788] 'process raft request' (duration: 566.581912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:41.413696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:40.842248Z","time spent":"571.087728ms","remote":"127.0.0.1:52884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:58:41.413964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.750283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:58:41.414071Z","caller":"traceutil/trace.go:171","msg":"trace[467655737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1998; }","duration":"134.795381ms","start":"2026-06-24T17:58:41.279241Z","end":"2026-06-24T17:58:41.414037Z","steps":["trace[467655737] 'agreement among raft nodes before linearized reading' (duration: 133.764032ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:41.575262Z","caller":"traceutil/trace.go:171","msg":"trace[1454361046] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"153.663068ms","start":"2026-06-24T17:58:41.421568Z","end":"2026-06-24T17:58:41.575231Z","steps":["trace[1454361046] 'process raft request' (duration: 93.655182ms)","trace[1454361046] 'compare' (duration: 59.778661ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:58:41.575738Z","caller":"traceutil/trace.go:171","msg":"trace[27337383] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"120.186145ms","start":"2026-06-24T17:58:41.45554Z","end":"2026-06-24T17:58:41.575726Z","steps":["trace[27337383] 'process raft request' (duration: 119.637244ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:41.575546Z","caller":"traceutil/trace.go:171","msg":"trace[464508084] linearizableReadLoop","detail":"{readStateIndex:2077; appliedIndex:2075; }","duration":"112.266655ms","start":"2026-06-24T17:58:41.463167Z","end":"2026-06-24T17:58:41.575434Z","steps":["trace[464508084] 'read index received' (duration: 52.051265ms)","trace[464508084] 'applied index is now lower than readState.Index' (duration: 60.21365ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:58:41.576173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.98932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-24T17:58:41.576358Z","caller":"traceutil/trace.go:171","msg":"trace[1138985305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2001; }","duration":"113.225273ms","start":"2026-06-24T17:58:41.46312Z","end":"2026-06-24T17:58:41.576346Z","steps":["trace[1138985305] 'agreement among raft nodes before linearized reading' (duration: 112.919328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:41.576696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.590964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-24T17:58:41.577054Z","caller":"traceutil/trace.go:171","msg":"trace[911846747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2001; }","duration":"109.996362ms","start":"2026-06-24T17:58:41.467033Z","end":"2026-06-24T17:58:41.57703Z","steps":["trace[911846747] 'agreement among raft nodes before linearized reading' (duration: 109.470522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:41.57681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.472327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-24T17:58:41.577509Z","caller":"traceutil/trace.go:171","msg":"trace[913668993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2001; }","duration":"106.18694ms","start":"2026-06-24T17:58:41.471304Z","end":"2026-06-24T17:58:41.577491Z","steps":["trace[913668993] 'agreement among raft nodes before linearized reading' (duration: 105.429446ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:43.779714Z","caller":"traceutil/trace.go:171","msg":"trace[31475327] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"133.854794ms","start":"2026-06-24T17:58:43.645836Z","end":"2026-06-24T17:58:43.779691Z","steps":["trace[31475327] 'process raft request' (duration: 133.765733ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:43.779982Z","caller":"traceutil/trace.go:171","msg":"trace[946018402] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"133.64024ms","start":"2026-06-24T17:58:43.64631Z","end":"2026-06-24T17:58:43.77995Z","steps":["trace[946018402] 'process raft request' (duration: 133.346024ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:43.779832Z","caller":"traceutil/trace.go:171","msg":"trace[2063777659] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"134.107869ms","start":"2026-06-24T17:58:43.645676Z","end":"2026-06-24T17:58:43.779784Z","steps":["trace[2063777659] 'process raft request' (duration: 130.117765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:45.157837Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224657118682086,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-24T17:58:45.258161Z","caller":"traceutil/trace.go:171","msg":"trace[663275931] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"835.129122ms","start":"2026-06-24T17:58:44.423007Z","end":"2026-06-24T17:58:45.258136Z","steps":["trace[663275931] 'process raft request' (duration: 834.957569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:45.258357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:44.422991Z","time spent":"835.256535ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:58:45.261024Z","caller":"traceutil/trace.go:171","msg":"trace[508594772] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"584.670732ms","start":"2026-06-24T17:58:44.676327Z","end":"2026-06-24T17:58:45.260998Z","steps":["trace[508594772] 'process raft request' (duration: 583.924109ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:45.261223Z","caller":"traceutil/trace.go:171","msg":"trace[1093497720] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"560.289868ms","start":"2026-06-24T17:58:44.700919Z","end":"2026-06-24T17:58:45.261209Z","steps":["trace[1093497720] 'process raft request' (duration: 560.031934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:45.261217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:44.676301Z","time spent":"584.798434ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:58:45.26132Z","caller":"traceutil/trace.go:171","msg":"trace[2033414914] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"559.36046ms","start":"2026-06-24T17:58:44.701944Z","end":"2026-06-24T17:58:45.261305Z","steps":["trace[2033414914] 'process raft request' (duration: 559.184207ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:45.26122Z","caller":"traceutil/trace.go:171","msg":"trace[453262867] linearizableReadLoop","detail":"{readStateIndex:2107; appliedIndex:2103; }","duration":"604.115513ms","start":"2026-06-24T17:58:44.657088Z","end":"2026-06-24T17:58:45.261203Z","steps":["trace[453262867] 'read index received' (duration: 600.958425ms)","trace[453262867] 'applied index is now lower than readState.Index' (duration: 3.156348ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:58:45.261354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.271306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11986"} {"level":"info","ts":"2026-06-24T17:58:45.261383Z","caller":"traceutil/trace.go:171","msg":"trace[304493813] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2030; }","duration":"604.335077ms","start":"2026-06-24T17:58:44.657038Z","end":"2026-06-24T17:58:45.261373Z","steps":["trace[304493813] 'agreement among raft nodes before linearized reading' (duration: 604.217105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:45.261394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:44.701915Z","time spent":"559.441462ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:58:45.261406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:44.657019Z","time spent":"604.381658ms","remote":"127.0.0.1:52884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12009,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-24T17:58:45.261428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.749358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T17:58:45.261455Z","caller":"traceutil/trace.go:171","msg":"trace[1228540058] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2030; }","duration":"587.81946ms","start":"2026-06-24T17:58:44.673629Z","end":"2026-06-24T17:58:45.261449Z","steps":["trace[1228540058] 'agreement among raft nodes before linearized reading' (duration: 587.765479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:45.261476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:44.673605Z","time spent":"587.865752ms","remote":"127.0.0.1:52970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:58:45.261332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:44.700887Z","time spent":"560.38804ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:58:45.261651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.469527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T17:58:45.261661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.546315ms","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-06-24T17:58:45.261673Z","caller":"traceutil/trace.go:171","msg":"trace[726512801] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2030; }","duration":"484.528109ms","start":"2026-06-24T17:58:44.777139Z","end":"2026-06-24T17:58:45.261667Z","steps":["trace[726512801] 'agreement among raft nodes before linearized reading' (duration: 484.481198ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:58:45.261688Z","caller":"traceutil/trace.go:171","msg":"trace[1501587470] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2030; }","duration":"400.600047ms","start":"2026-06-24T17:58:44.86108Z","end":"2026-06-24T17:58:45.26168Z","steps":["trace[1501587470] 'agreement among raft nodes before linearized reading' (duration: 400.498905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:45.261695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:44.777122Z","time spent":"484.567589ms","remote":"127.0.0.1:53050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:58:45.261711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:44.861066Z","time spent":"400.640787ms","remote":"127.0.0.1:45812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-24T17:58:45.261853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.267263ms","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-06-24T17:58:45.261877Z","caller":"traceutil/trace.go:171","msg":"trace[897951834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2030; }","duration":"348.329894ms","start":"2026-06-24T17:58:44.913541Z","end":"2026-06-24T17:58:45.261871Z","steps":["trace[897951834] 'agreement among raft nodes before linearized reading' (duration: 348.270093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:58:45.261899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:58:44.91352Z","time spent":"348.372796ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-24T17:59:07.904598Z","caller":"traceutil/trace.go:171","msg":"trace[630617823] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2238; }","duration":"171.489775ms","start":"2026-06-24T17:59:07.733081Z","end":"2026-06-24T17:59:07.904571Z","steps":["trace[630617823] 'read index received' (duration: 171.220671ms)","trace[630617823] 'applied index is now lower than readState.Index' (duration: 267.964µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:59:07.905185Z","caller":"traceutil/trace.go:171","msg":"trace[838095175] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"238.377655ms","start":"2026-06-24T17:59:07.666787Z","end":"2026-06-24T17:59:07.905165Z","steps":["trace[838095175] 'process raft request' (duration: 237.582072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:07.905234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.136445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-24T17:59:07.905283Z","caller":"traceutil/trace.go:171","msg":"trace[135235445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2157; }","duration":"172.253558ms","start":"2026-06-24T17:59:07.733018Z","end":"2026-06-24T17:59:07.905271Z","steps":["trace[135235445] 'agreement among raft nodes before linearized reading' (duration: 171.652428ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:59:08.02354Z","caller":"traceutil/trace.go:171","msg":"trace[1501536494] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2239; }","duration":"110.702945ms","start":"2026-06-24T17:59:07.912818Z","end":"2026-06-24T17:59:08.023521Z","steps":["trace[1501536494] 'read index received' (duration: 91.415487ms)","trace[1501536494] 'applied index is now lower than readState.Index' (duration: 19.286448ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:59:08.023621Z","caller":"traceutil/trace.go:171","msg":"trace[583398457] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"114.229094ms","start":"2026-06-24T17:59:07.909338Z","end":"2026-06-24T17:59:08.023567Z","steps":["trace[583398457] 'process raft request' (duration: 94.934385ms)","trace[583398457] 'compare' (duration: 19.169467ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:59:08.023752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.915209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-24T17:59:08.023893Z","caller":"traceutil/trace.go:171","msg":"trace[2039231442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2158; }","duration":"111.090581ms","start":"2026-06-24T17:59:07.912789Z","end":"2026-06-24T17:59:08.023879Z","steps":["trace[2039231442] 'agreement among raft nodes before linearized reading' (duration: 110.806757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:08.023905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.099948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-24T17:59:08.024108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.780197ms","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-06-24T17:59:08.024153Z","caller":"traceutil/trace.go:171","msg":"trace[1287298347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2158; }","duration":"110.838797ms","start":"2026-06-24T17:59:07.913303Z","end":"2026-06-24T17:59:08.024141Z","steps":["trace[1287298347] 'agreement among raft nodes before linearized reading' (duration: 110.334159ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:59:08.0242Z","caller":"traceutil/trace.go:171","msg":"trace[772284177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2158; }","duration":"109.286031ms","start":"2026-06-24T17:59:07.914766Z","end":"2026-06-24T17:59:08.024052Z","steps":["trace[772284177] 'agreement among raft nodes before linearized reading' (duration: 108.914455ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:59:08.227837Z","caller":"traceutil/trace.go:171","msg":"trace[156684253] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"198.725652ms","start":"2026-06-24T17:59:08.029079Z","end":"2026-06-24T17:59:08.227804Z","steps":["trace[156684253] 'process raft request' (duration: 191.458392ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:59:08.232057Z","caller":"traceutil/trace.go:171","msg":"trace[6177367] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"202.117078ms","start":"2026-06-24T17:59:08.02992Z","end":"2026-06-24T17:59:08.232037Z","steps":["trace[6177367] 'process raft request' (duration: 202.077197ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:59:08.232247Z","caller":"traceutil/trace.go:171","msg":"trace[1338001682] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"202.689417ms","start":"2026-06-24T17:59:08.029549Z","end":"2026-06-24T17:59:08.232238Z","steps":["trace[1338001682] 'process raft request' (duration: 202.336881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:08.633433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.861189ms","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-06-24T17:59:08.633517Z","caller":"traceutil/trace.go:171","msg":"trace[433304967] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2161; }","duration":"235.00365ms","start":"2026-06-24T17:59:08.398494Z","end":"2026-06-24T17:59:08.633498Z","steps":["trace[433304967] 'range keys from in-memory index tree' (duration: 234.615194ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:59:09.318162Z","caller":"traceutil/trace.go:171","msg":"trace[1938617892] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"363.136596ms","start":"2026-06-24T17:59:08.955003Z","end":"2026-06-24T17:59:09.31814Z","steps":["trace[1938617892] 'process raft request' (duration: 362.404764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:09.318302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:08.954993Z","time spent":"363.232267ms","remote":"127.0.0.1:52790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:59:09.479793Z","caller":"traceutil/trace.go:171","msg":"trace[1346056956] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"139.131998ms","start":"2026-06-24T17:59:09.340611Z","end":"2026-06-24T17:59:09.479743Z","steps":["trace[1346056956] 'process raft request' (duration: 82.037878ms)","trace[1346056956] 'compare' (duration: 56.996208ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:59:09.550487Z","caller":"traceutil/trace.go:171","msg":"trace[1101124190] linearizableReadLoop","detail":"{readStateIndex:2247; appliedIndex:2244; }","duration":"168.191036ms","start":"2026-06-24T17:59:09.382273Z","end":"2026-06-24T17:59:09.550464Z","steps":["trace[1101124190] 'read index received' (duration: 40.390874ms)","trace[1101124190] 'applied index is now lower than readState.Index' (duration: 127.799352ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:59:09.550524Z","caller":"traceutil/trace.go:171","msg":"trace[2854089] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"206.298163ms","start":"2026-06-24T17:59:09.344204Z","end":"2026-06-24T17:59:09.550502Z","steps":["trace[2854089] 'process raft request' (duration: 206.11701ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:59:09.550579Z","caller":"traceutil/trace.go:171","msg":"trace[1748867376] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"193.894018ms","start":"2026-06-24T17:59:09.356648Z","end":"2026-06-24T17:59:09.550542Z","steps":["trace[1748867376] 'process raft request' (duration: 193.781356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:09.551698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.581643ms","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-06-24T17:59:09.551782Z","caller":"traceutil/trace.go:171","msg":"trace[283081589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2165; }","duration":"169.547778ms","start":"2026-06-24T17:59:09.382218Z","end":"2026-06-24T17:59:09.551766Z","steps":["trace[283081589] 'agreement among raft nodes before linearized reading' (duration: 168.402279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:10.42073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224657118682423,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-24T17:59:10.477095Z","caller":"traceutil/trace.go:171","msg":"trace[1395786017] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2248; }","duration":"556.390598ms","start":"2026-06-24T17:59:09.920681Z","end":"2026-06-24T17:59:10.477072Z","steps":["trace[1395786017] 'read index received' (duration: 556.172373ms)","trace[1395786017] 'applied index is now lower than readState.Index' (duration: 217.345µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:59:10.477193Z","caller":"traceutil/trace.go:171","msg":"trace[625047051] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"567.492041ms","start":"2026-06-24T17:59:09.909646Z","end":"2026-06-24T17:59:10.477138Z","steps":["trace[625047051] 'process raft request' (duration: 567.268357ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:10.477308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.54607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T17:59:10.477382Z","caller":"traceutil/trace.go:171","msg":"trace[1563446996] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2167; }","duration":"556.727613ms","start":"2026-06-24T17:59:09.920642Z","end":"2026-06-24T17:59:10.477369Z","steps":["trace[1563446996] 'agreement among raft nodes before linearized reading' (duration: 556.554301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:10.47746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:09.920623Z","time spent":"556.796734ms","remote":"127.0.0.1:60018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:59:10.477465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:09.909623Z","time spent":"567.649513ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:59:10.477709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.458854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-24T17:59:10.477778Z","caller":"traceutil/trace.go:171","msg":"trace[1471017526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2167; }","duration":"448.580336ms","start":"2026-06-24T17:59:10.029181Z","end":"2026-06-24T17:59:10.477761Z","steps":["trace[1471017526] 'agreement among raft nodes before linearized reading' (duration: 448.174549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:10.477893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.252848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-24T17:59:10.47798Z","caller":"traceutil/trace.go:171","msg":"trace[1705150383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2167; }","duration":"242.37739ms","start":"2026-06-24T17:59:10.235587Z","end":"2026-06-24T17:59:10.477964Z","steps":["trace[1705150383] 'agreement among raft nodes before linearized reading' (duration: 242.146906ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:10.477996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.342509ms","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-06-24T17:59:10.478104Z","caller":"traceutil/trace.go:171","msg":"trace[922254697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2167; }","duration":"242.514042ms","start":"2026-06-24T17:59:10.235571Z","end":"2026-06-24T17:59:10.478085Z","steps":["trace[922254697] 'agreement among raft nodes before linearized reading' (duration: 242.214857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:10.478089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.781106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-24T17:59:10.478199Z","caller":"traceutil/trace.go:171","msg":"trace[566326379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2167; }","duration":"245.901237ms","start":"2026-06-24T17:59:10.232248Z","end":"2026-06-24T17:59:10.478149Z","steps":["trace[566326379] 'agreement among raft nodes before linearized reading' (duration: 245.612512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:10.477982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:10.02916Z","time spent":"448.803409ms","remote":"127.0.0.1:52952","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-06-24T17:59:10.848283Z","caller":"traceutil/trace.go:171","msg":"trace[1108882171] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"365.677657ms","start":"2026-06-24T17:59:10.482579Z","end":"2026-06-24T17:59:10.848256Z","steps":["trace[1108882171] 'process raft request' (duration: 356.04591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:10.848631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:10.482557Z","time spent":"365.794829ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:59:11.3109Z","caller":"traceutil/trace.go:171","msg":"trace[1727464466] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"825.869032ms","start":"2026-06-24T17:59:10.485005Z","end":"2026-06-24T17:59:11.310874Z","steps":["trace[1727464466] 'process raft request' (duration: 825.790731ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:59:11.310898Z","caller":"traceutil/trace.go:171","msg":"trace[332104818] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"826.259869ms","start":"2026-06-24T17:59:10.484598Z","end":"2026-06-24T17:59:11.310858Z","steps":["trace[332104818] 'process raft request' (duration: 826.016195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:11.311052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:10.484978Z","time spent":"825.996765ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:59:11.311092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:10.484574Z","time spent":"826.421241ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:59:11.3111Z","caller":"traceutil/trace.go:171","msg":"trace[1909550737] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2249; }","duration":"816.159163ms","start":"2026-06-24T17:59:10.494921Z","end":"2026-06-24T17:59:11.31108Z","steps":["trace[1909550737] 'read index received' (duration: 343.722028ms)","trace[1909550737] 'applied index is now lower than readState.Index' (duration: 472.435705ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:59:11.311204Z","caller":"traceutil/trace.go:171","msg":"trace[1024517084] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"825.351693ms","start":"2026-06-24T17:59:10.48584Z","end":"2026-06-24T17:59:11.311192Z","steps":["trace[1024517084] 'process raft request' (duration: 824.996458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:11.311269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.346175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T17:59:11.311271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.390619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:59:11.311315Z","caller":"traceutil/trace.go:171","msg":"trace[1449412316] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2171; }","duration":"816.423387ms","start":"2026-06-24T17:59:10.494876Z","end":"2026-06-24T17:59:11.3113Z","steps":["trace[1449412316] 'agreement among raft nodes before linearized reading' (duration: 816.348376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:11.311371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.939894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:59:11.31141Z","caller":"traceutil/trace.go:171","msg":"trace[1124728935] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2171; }","duration":"790.025066ms","start":"2026-06-24T17:59:10.521376Z","end":"2026-06-24T17:59:11.311401Z","steps":["trace[1124728935] 'agreement among raft nodes before linearized reading' (duration: 789.965815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:11.311406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.43494ms","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":"warn","ts":"2026-06-24T17:59:11.311451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:10.521349Z","time spent":"790.092126ms","remote":"127.0.0.1:40002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-24T17:59:11.311454Z","caller":"traceutil/trace.go:171","msg":"trace[1368780236] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2171; }","duration":"650.531631ms","start":"2026-06-24T17:59:10.660912Z","end":"2026-06-24T17:59:11.311444Z","steps":["trace[1368780236] 'agreement among raft nodes before linearized reading' (duration: 650.394679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:11.311494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:10.660884Z","time spent":"650.603872ms","remote":"127.0.0.1:45812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-24T17:59:11.31132Z","caller":"traceutil/trace.go:171","msg":"trace[2005043905] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2171; }","duration":"796.46505ms","start":"2026-06-24T17:59:10.514842Z","end":"2026-06-24T17:59:11.311307Z","steps":["trace[2005043905] 'agreement among raft nodes before linearized reading' (duration: 796.387299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:11.311605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:10.51483Z","time spent":"796.756125ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:59:11.311371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:10.494861Z","time spent":"816.500698ms","remote":"127.0.0.1:53122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-24T17:59:11.311272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:10.485799Z","time spent":"825.429914ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:59:11.907237Z","caller":"traceutil/trace.go:171","msg":"trace[520493931] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"421.824579ms","start":"2026-06-24T17:59:11.485389Z","end":"2026-06-24T17:59:11.907213Z","steps":["trace[520493931] 'process raft request' (duration: 416.536372ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:11.907554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:11.485361Z","time spent":"421.9432ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:59:11.923823Z","caller":"traceutil/trace.go:171","msg":"trace[370093570] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"368.236164ms","start":"2026-06-24T17:59:11.55557Z","end":"2026-06-24T17:59:11.923806Z","steps":["trace[370093570] 'process raft request' (duration: 368.099422ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:59:11.924016Z","caller":"traceutil/trace.go:171","msg":"trace[1678224486] linearizableReadLoop","detail":"{readStateIndex:2256; appliedIndex:2253; }","duration":"349.805194ms","start":"2026-06-24T17:59:11.574172Z","end":"2026-06-24T17:59:11.923978Z","steps":["trace[1678224486] 'read index received' (duration: 327.779215ms)","trace[1678224486] 'applied index is now lower than readState.Index' (duration: 22.023259ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:59:11.924096Z","caller":"traceutil/trace.go:171","msg":"trace[19997446] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"367.000074ms","start":"2026-06-24T17:59:11.556979Z","end":"2026-06-24T17:59:11.923979Z","steps":["trace[19997446] 'process raft request' (duration: 366.793051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:11.92421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:11.556973Z","time spent":"367.193297ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:59:11.924301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.113017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.108\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-24T17:59:11.924335Z","caller":"traceutil/trace.go:171","msg":"trace[127506983] range","detail":"{range_begin:/registry/masterleases/199.19.213.108; range_end:; response_count:1; response_revision:2174; }","duration":"104.185239ms","start":"2026-06-24T17:59:11.82014Z","end":"2026-06-24T17:59:11.924325Z","steps":["trace[127506983] 'agreement among raft nodes before linearized reading' (duration: 104.065047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:11.924441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.19317ms","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-06-24T17:59:11.924498Z","caller":"traceutil/trace.go:171","msg":"trace[1235793139] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2174; }","duration":"350.361833ms","start":"2026-06-24T17:59:11.574119Z","end":"2026-06-24T17:59:11.924481Z","steps":["trace[1235793139] 'agreement among raft nodes before linearized reading' (duration: 350.048738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:11.924552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:11.574095Z","time spent":"350.441534ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-24T17:59:11.924139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:11.55555Z","time spent":"368.352726ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:59:45.53607Z","caller":"traceutil/trace.go:171","msg":"trace[140240351] transaction","detail":"{read_only:false; response_revision:2407; number_of_response:1; }","duration":"216.121606ms","start":"2026-06-24T17:59:45.319914Z","end":"2026-06-24T17:59:45.536036Z","steps":["trace[140240351] 'process raft request' (duration: 215.827481ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:59:55.074138Z","caller":"traceutil/trace.go:171","msg":"trace[1437064730] transaction","detail":"{read_only:false; response_revision:2514; number_of_response:1; }","duration":"458.080779ms","start":"2026-06-24T17:59:54.616033Z","end":"2026-06-24T17:59:55.074114Z","steps":["trace[1437064730] 'process raft request' (duration: 457.915777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:55.07429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:54.616004Z","time spent":"458.214161ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:59:55.104376Z","caller":"traceutil/trace.go:171","msg":"trace[1179923736] transaction","detail":"{read_only:false; response_revision:2515; number_of_response:1; }","duration":"456.256893ms","start":"2026-06-24T17:59:54.648084Z","end":"2026-06-24T17:59:55.104341Z","steps":["trace[1179923736] 'process raft request' (duration: 455.859407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:59:55.104625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:59:54.648059Z","time spent":"456.459975ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:00:28.543486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008945714s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-24T18:00:28.543719Z","caller":"traceutil/trace.go:171","msg":"trace[2050535133] linearizableReadLoop","detail":"{readStateIndex:2792; appliedIndex:2791; }","duration":"1.293922968s","start":"2026-06-24T18:00:27.249767Z","end":"2026-06-24T18:00:28.54369Z","steps":["trace[2050535133] 'read index received' (duration: 284.68203ms)","trace[2050535133] 'applied index is now lower than readState.Index' (duration: 1.009239868s)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:28.54402Z","caller":"traceutil/trace.go:171","msg":"trace[1248434958] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"215.979813ms","start":"2026-06-24T18:00:28.328003Z","end":"2026-06-24T18:00:28.543983Z","steps":["trace[1248434958] 'process raft request' (duration: 215.597418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.588271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338462178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T18:00:28.588594Z","caller":"traceutil/trace.go:171","msg":"trace[1652481613] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"259.231726ms","start":"2026-06-24T18:00:28.329334Z","end":"2026-06-24T18:00:28.588566Z","steps":["trace[1652481613] 'process raft request' (duration: 245.564355ms)","trace[1652481613] 'compare' (duration: 13.096864ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:28.58869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314424348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T18:00:28.588755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.365694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T18:00:28.588787Z","caller":"traceutil/trace.go:171","msg":"trace[2018985942] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2695; }","duration":"1.31457019s","start":"2026-06-24T18:00:27.274193Z","end":"2026-06-24T18:00:28.588763Z","steps":["trace[2018985942] 'agreement among raft nodes before linearized reading' (duration: 1.314425569s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.58888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.589593ms","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-06-24T18:00:28.588878Z","caller":"traceutil/trace.go:171","msg":"trace[464664830] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2695; }","duration":"800.485406ms","start":"2026-06-24T18:00:27.788309Z","end":"2026-06-24T18:00:28.588794Z","steps":["trace[464664830] 'agreement among raft nodes before linearized reading' (duration: 800.356794ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:28.588906Z","caller":"traceutil/trace.go:171","msg":"trace[1853766735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2695; }","duration":"189.662304ms","start":"2026-06-24T18:00:28.399236Z","end":"2026-06-24T18:00:28.588899Z","steps":["trace[1853766735] 'agreement among raft nodes before linearized reading' (duration: 189.509762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.588967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:27.788298Z","time spent":"800.622657ms","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-24T18:00:28.589048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.806026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:28.589076Z","caller":"traceutil/trace.go:171","msg":"trace[1108979247] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2695; }","duration":"106.867516ms","start":"2026-06-24T18:00:28.482201Z","end":"2026-06-24T18:00:28.589068Z","steps":["trace[1108979247] 'agreement among raft nodes before linearized reading' (duration: 106.812906ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:28.589133Z","caller":"traceutil/trace.go:171","msg":"trace[1050427009] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"107.964861ms","start":"2026-06-24T18:00:28.481125Z","end":"2026-06-24T18:00:28.58909Z","steps":["trace[1050427009] 'process raft request' (duration: 107.429474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.589177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.815046ms","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-06-24T18:00:28.589299Z","caller":"traceutil/trace.go:171","msg":"trace[1482112284] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2695; }","duration":"493.974367ms","start":"2026-06-24T18:00:28.095248Z","end":"2026-06-24T18:00:28.589223Z","steps":["trace[1482112284] 'agreement among raft nodes before linearized reading' (duration: 493.663383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.589352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:28.095226Z","time spent":"494.111169ms","remote":"127.0.0.1:52888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-06-24T18:00:28.589372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.269097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T18:00:28.589395Z","caller":"traceutil/trace.go:171","msg":"trace[2026215800] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2695; }","duration":"465.323899ms","start":"2026-06-24T18:00:28.124065Z","end":"2026-06-24T18:00:28.589389Z","steps":["trace[2026215800] 'agreement among raft nodes before linearized reading' (duration: 465.280948ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.589421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:28.124049Z","time spent":"465.365399ms","remote":"127.0.0.1:53124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":3,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-06-24T18:00:28.589502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176416487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T18:00:28.589628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.15771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-24T18:00:28.589722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311786814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:28.589705Z","caller":"traceutil/trace.go:171","msg":"trace[748770815] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2695; }","duration":"629.284542ms","start":"2026-06-24T18:00:27.960408Z","end":"2026-06-24T18:00:28.589692Z","steps":["trace[748770815] 'agreement among raft nodes before linearized reading' (duration: 628.926627ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:28.589772Z","caller":"traceutil/trace.go:171","msg":"trace[1099183748] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"256.361328ms","start":"2026-06-24T18:00:28.333398Z","end":"2026-06-24T18:00:28.58976Z","steps":["trace[1099183748] 'process raft request' (duration: 255.005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.589797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197111212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18bc163dd720c73c\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-06-24T18:00:28.589814Z","caller":"traceutil/trace.go:171","msg":"trace[153998697] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18bc163dd720c73c; range_end:; response_count:1; response_revision:2695; }","duration":"1.197150723s","start":"2026-06-24T18:00:27.392659Z","end":"2026-06-24T18:00:28.589809Z","steps":["trace[153998697] 'agreement among raft nodes before linearized reading' (duration: 1.197097232s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.589778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:27.960312Z","time spent":"629.454334ms","remote":"127.0.0.1:52790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":296,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-06-24T18:00:28.58985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:27.392646Z","time spent":"1.197199083s","remote":"127.0.0.1:52790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":802,"request content":"key:\"/registry/events/auth-system/keycloak-0.18bc163dd720c73c\" "} {"level":"info","ts":"2026-06-24T18:00:28.589744Z","caller":"traceutil/trace.go:171","msg":"trace[1536910387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2695; }","duration":"1.311828994s","start":"2026-06-24T18:00:27.277909Z","end":"2026-06-24T18:00:28.589738Z","steps":["trace[1536910387] 'agreement among raft nodes before linearized reading' (duration: 1.311792024s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.589954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:27.2779Z","time spent":"1.312044038s","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T18:00:28.590183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.262518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-24T18:00:28.590209Z","caller":"traceutil/trace.go:171","msg":"trace[1439136516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2695; }","duration":"829.331138ms","start":"2026-06-24T18:00:27.760872Z","end":"2026-06-24T18:00:28.590203Z","steps":["trace[1439136516] 'agreement among raft nodes before linearized reading' (duration: 829.266977ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.590227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:27.76085Z","time spent":"829.372338ms","remote":"127.0.0.1:52952","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":"warn","ts":"2026-06-24T18:00:28.590299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.952923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-24T18:00:28.59037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:27.274169Z","time spent":"1.316167662s","remote":"127.0.0.1:53122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-24T18:00:28.59053Z","caller":"traceutil/trace.go:171","msg":"trace[1299442402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2695; }","duration":"829.196346ms","start":"2026-06-24T18:00:27.761326Z","end":"2026-06-24T18:00:28.590522Z","steps":["trace[1299442402] 'agreement among raft nodes before linearized reading' (duration: 828.942523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.590637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.046535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-24T18:00:28.59072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315236681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-24T18:00:28.590775Z","caller":"traceutil/trace.go:171","msg":"trace[760031924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2695; }","duration":"1.315301901s","start":"2026-06-24T18:00:27.275456Z","end":"2026-06-24T18:00:28.590758Z","steps":["trace[760031924] 'agreement among raft nodes before linearized reading' (duration: 1.315155479s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.590811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:27.275449Z","time spent":"1.315356322s","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-24T18:00:28.590785Z","caller":"traceutil/trace.go:171","msg":"trace[569182053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2695; }","duration":"832.201526ms","start":"2026-06-24T18:00:27.75854Z","end":"2026-06-24T18:00:28.590742Z","steps":["trace[569182053] 'agreement among raft nodes before linearized reading' (duration: 831.844922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:28.590905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:27.75852Z","time spent":"832.372969ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-24T18:00:28.590712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:27.761317Z","time spent":"829.385229ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-24T18:00:28.590187Z","caller":"traceutil/trace.go:171","msg":"trace[1811289818] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2695; }","duration":"1.176631379s","start":"2026-06-24T18:00:27.413039Z","end":"2026-06-24T18:00:28.589671Z","steps":["trace[1811289818] 'agreement among raft nodes before linearized reading' (duration: 1.17593066s)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:28.591209Z","caller":"traceutil/trace.go:171","msg":"trace[82444502] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2692; }","duration":"1.33860869s","start":"2026-06-24T18:00:27.249761Z","end":"2026-06-24T18:00:28.58837Z","steps":["trace[82444502] 'agreement among raft nodes before linearized reading' (duration: 1.294605877s)","trace[82444502] 'count revisions from in-memory index tree' (duration: 43.810831ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:28.591271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:27.249707Z","time spent":"1.34154723s","remote":"127.0.0.1:52946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-06-24T18:00:28.591284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:27.413029Z","time spent":"1.17820075s","remote":"127.0.0.1:35474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-06-24T18:04:16.500568Z","caller":"traceutil/trace.go:171","msg":"trace[552183051] transaction","detail":"{read_only:false; response_revision:4589; number_of_response:1; }","duration":"294.348831ms","start":"2026-06-24T18:04:16.206195Z","end":"2026-06-24T18:04:16.500544Z","steps":["trace[552183051] 'process raft request' (duration: 294.174128ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:16.50061Z","caller":"traceutil/trace.go:171","msg":"trace[1897818178] linearizableReadLoop","detail":"{readStateIndex:4765; appliedIndex:4765; }","duration":"175.234885ms","start":"2026-06-24T18:04:16.325346Z","end":"2026-06-24T18:04:16.500581Z","steps":["trace[1897818178] 'read index received' (duration: 175.203284ms)","trace[1897818178] 'applied index is now lower than readState.Index' (duration: 30.041µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:04:16.501063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.701404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-pprtm\" ","response":"range_response_count:1 size:5151"} {"level":"info","ts":"2026-06-24T18:04:16.501123Z","caller":"traceutil/trace.go:171","msg":"trace[883304042] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-pprtm; range_end:; response_count:1; response_revision:4589; }","duration":"175.796467ms","start":"2026-06-24T18:04:16.32531Z","end":"2026-06-24T18:04:16.501106Z","steps":["trace[883304042] 'agreement among raft nodes before linearized reading' (duration: 175.423339ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:04:23.419118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.015353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:04:23.419298Z","caller":"traceutil/trace.go:171","msg":"trace[47426359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4699; }","duration":"139.236618ms","start":"2026-06-24T18:04:23.280038Z","end":"2026-06-24T18:04:23.419275Z","steps":["trace[47426359] 'range keys from in-memory index tree' (duration: 138.934552ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:23.682949Z","caller":"traceutil/trace.go:171","msg":"trace[1004619090] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"100.379443ms","start":"2026-06-24T18:04:23.582535Z","end":"2026-06-24T18:04:23.682914Z","steps":["trace[1004619090] 'process raft request' (duration: 96.375255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:04:26.029729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.870302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:04:26.029818Z","caller":"traceutil/trace.go:171","msg":"trace[2145793830] linearizableReadLoop","detail":"{readStateIndex:4925; appliedIndex:4924; }","duration":"113.819638ms","start":"2026-06-24T18:04:25.915983Z","end":"2026-06-24T18:04:26.029802Z","steps":["trace[2145793830] 'read index received' (duration: 9.849974ms)","trace[2145793830] 'applied index is now lower than readState.Index' (duration: 103.968524ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:04:26.029967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.979901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-16dca302-831e-47bc-94c5-bf54985f8b2a\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-06-24T18:04:26.029996Z","caller":"traceutil/trace.go:171","msg":"trace[1127848002] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-16dca302-831e-47bc-94c5-bf54985f8b2a; range_end:; response_count:1; response_revision:4747; }","duration":"114.033461ms","start":"2026-06-24T18:04:25.915952Z","end":"2026-06-24T18:04:26.029986Z","steps":["trace[1127848002] 'agreement among raft nodes before linearized reading' (duration: 113.892259ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:26.030271Z","caller":"traceutil/trace.go:171","msg":"trace[930022983] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"190.293364ms","start":"2026-06-24T18:04:25.839965Z","end":"2026-06-24T18:04:26.030258Z","steps":["trace[930022983] 'process raft request' (duration: 85.81832ms)","trace[930022983] 'compare' (duration: 103.77976ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:04:36.255151Z","caller":"traceutil/trace.go:171","msg":"trace[1917736154] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"340.845155ms","start":"2026-06-24T18:04:35.914283Z","end":"2026-06-24T18:04:36.255128Z","steps":["trace[1917736154] 'process raft request' (duration: 336.296148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:04:36.255306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:04:35.914263Z","time spent":"340.959207ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:04:36.256564Z","caller":"traceutil/trace.go:171","msg":"trace[68429394] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"340.851565ms","start":"2026-06-24T18:04:35.915688Z","end":"2026-06-24T18:04:36.256539Z","steps":["trace[68429394] 'process raft request' (duration: 340.737733ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:36.25655Z","caller":"traceutil/trace.go:171","msg":"trace[160403794] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"340.878815ms","start":"2026-06-24T18:04:35.915629Z","end":"2026-06-24T18:04:36.256507Z","steps":["trace[160403794] 'process raft request' (duration: 340.668591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:04:36.256709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:04:35.915684Z","time spent":"340.951757ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:04:36.256774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:04:35.91561Z","time spent":"341.11048ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:04:36.256501Z","caller":"traceutil/trace.go:171","msg":"trace[1636976709] linearizableReadLoop","detail":"{readStateIndex:5061; appliedIndex:5058; }","duration":"239.251116ms","start":"2026-06-24T18:04:36.01723Z","end":"2026-06-24T18:04:36.256481Z","steps":["trace[1636976709] 'read index received' (duration: 233.427074ms)","trace[1636976709] 'applied index is now lower than readState.Index' (duration: 5.823142ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:04:36.256691Z","caller":"traceutil/trace.go:171","msg":"trace[205536658] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"178.307658ms","start":"2026-06-24T18:04:36.078277Z","end":"2026-06-24T18:04:36.256585Z","steps":["trace[205536658] 'process raft request' (duration: 178.175955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:04:36.256968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.738675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16552"} {"level":"info","ts":"2026-06-24T18:04:36.257002Z","caller":"traceutil/trace.go:171","msg":"trace[1950503994] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4880; }","duration":"239.818236ms","start":"2026-06-24T18:04:36.017175Z","end":"2026-06-24T18:04:36.256993Z","steps":["trace[1950503994] 'agreement among raft nodes before linearized reading' (duration: 239.690574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:04:36.256972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.884562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-24T18:04:36.257312Z","caller":"traceutil/trace.go:171","msg":"trace[1334780389] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4880; }","duration":"211.264549ms","start":"2026-06-24T18:04:36.046034Z","end":"2026-06-24T18:04:36.257298Z","steps":["trace[1334780389] 'agreement among raft nodes before linearized reading' (duration: 210.851011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:04:36.257273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.787801ms","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-06-24T18:04:36.257574Z","caller":"traceutil/trace.go:171","msg":"trace[1901679818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4880; }","duration":"185.136508ms","start":"2026-06-24T18:04:36.072416Z","end":"2026-06-24T18:04:36.257552Z","steps":["trace[1901679818] 'agreement among raft nodes before linearized reading' (duration: 184.69933ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:36.461066Z","caller":"traceutil/trace.go:171","msg":"trace[1267587806] linearizableReadLoop","detail":"{readStateIndex:5064; appliedIndex:5063; }","duration":"121.300275ms","start":"2026-06-24T18:04:36.339742Z","end":"2026-06-24T18:04:36.461043Z","steps":["trace[1267587806] 'read index received' (duration: 121.124702ms)","trace[1267587806] 'applied index is now lower than readState.Index' (duration: 174.393µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:04:36.461226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.078178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:04:36.46127Z","caller":"traceutil/trace.go:171","msg":"trace[1401294267] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:4882; }","duration":"184.162309ms","start":"2026-06-24T18:04:36.277093Z","end":"2026-06-24T18:04:36.461256Z","steps":["trace[1401294267] 'agreement among raft nodes before linearized reading' (duration: 184.072567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:04:36.46129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.488475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-06-24T18:04:36.461429Z","caller":"traceutil/trace.go:171","msg":"trace[1563200032] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4882; }","duration":"174.670398ms","start":"2026-06-24T18:04:36.286739Z","end":"2026-06-24T18:04:36.46141Z","steps":["trace[1563200032] 'agreement among raft nodes before linearized reading' (duration: 174.327391ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:36.461696Z","caller":"traceutil/trace.go:171","msg":"trace[660968281] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"190.318568ms","start":"2026-06-24T18:04:36.271349Z","end":"2026-06-24T18:04:36.461667Z","steps":["trace[660968281] 'process raft request' (duration: 189.511262ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:36.480249Z","caller":"traceutil/trace.go:171","msg":"trace[745195544] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"118.080033ms","start":"2026-06-24T18:04:36.362145Z","end":"2026-06-24T18:04:36.480225Z","steps":["trace[745195544] 'process raft request' (duration: 117.894189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:04:36.480346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.676919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-16dca302-831e-47bc-94c5-bf54985f8b2a\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-24T18:04:36.480435Z","caller":"traceutil/trace.go:171","msg":"trace[1175054303] range","detail":"{range_begin:/registry/persistentvolumes/pvc-16dca302-831e-47bc-94c5-bf54985f8b2a; range_end:; response_count:1; response_revision:4883; }","duration":"137.796981ms","start":"2026-06-24T18:04:36.342618Z","end":"2026-06-24T18:04:36.480415Z","steps":["trace[1175054303] 'agreement among raft nodes before linearized reading' (duration: 137.599097ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:36.636281Z","caller":"traceutil/trace.go:171","msg":"trace[244374131] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"107.704254ms","start":"2026-06-24T18:04:36.528546Z","end":"2026-06-24T18:04:36.63625Z","steps":["trace[244374131] 'process raft request' (duration: 56.388011ms)","trace[244374131] 'compare' (duration: 50.970117ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:04:36.636554Z","caller":"traceutil/trace.go:171","msg":"trace[841730579] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"107.467529ms","start":"2026-06-24T18:04:36.529061Z","end":"2026-06-24T18:04:36.636528Z","steps":["trace[841730579] 'process raft request' (duration: 107.011231ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:14.552073Z","caller":"traceutil/trace.go:171","msg":"trace[2112260026] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"146.582404ms","start":"2026-06-24T18:05:14.405456Z","end":"2026-06-24T18:05:14.552039Z","steps":["trace[2112260026] 'process raft request' (duration: 146.311509ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:18.827256Z","caller":"traceutil/trace.go:171","msg":"trace[2040946302] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"150.261536ms","start":"2026-06-24T18:05:18.676966Z","end":"2026-06-24T18:05:18.827227Z","steps":["trace[2040946302] 'process raft request' (duration: 150.050942ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:18.827267Z","caller":"traceutil/trace.go:171","msg":"trace[1815243922] linearizableReadLoop","detail":"{readStateIndex:5425; appliedIndex:5421; }","duration":"137.479227ms","start":"2026-06-24T18:05:18.689761Z","end":"2026-06-24T18:05:18.82724Z","steps":["trace[1815243922] 'read index received' (duration: 46.96791ms)","trace[1815243922] 'applied index is now lower than readState.Index' (duration: 90.510327ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:05:18.827404Z","caller":"traceutil/trace.go:171","msg":"trace[1256457804] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"150.330556ms","start":"2026-06-24T18:05:18.677062Z","end":"2026-06-24T18:05:18.827392Z","steps":["trace[1256457804] 'process raft request' (duration: 150.098172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:05:18.827636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.856463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:05:18.827668Z","caller":"traceutil/trace.go:171","msg":"trace[946777590] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5232; }","duration":"137.917804ms","start":"2026-06-24T18:05:18.689742Z","end":"2026-06-24T18:05:18.827659Z","steps":["trace[946777590] 'agreement among raft nodes before linearized reading' (duration: 137.794912ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:18.827629Z","caller":"traceutil/trace.go:171","msg":"trace[1263665960] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"148.015425ms","start":"2026-06-24T18:05:18.679578Z","end":"2026-06-24T18:05:18.827593Z","steps":["trace[1263665960] 'process raft request' (duration: 147.624568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:05:18.827718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.727261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:05:18.827856Z","caller":"traceutil/trace.go:171","msg":"trace[1027527770] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5232; }","duration":"137.882993ms","start":"2026-06-24T18:05:18.689966Z","end":"2026-06-24T18:05:18.827849Z","steps":["trace[1027527770] 'agreement among raft nodes before linearized reading' (duration: 137.71067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:05:18.827863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.517154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-06-24T18:05:18.82792Z","caller":"traceutil/trace.go:171","msg":"trace[1224480506] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5232; }","duration":"129.589215ms","start":"2026-06-24T18:05:18.698316Z","end":"2026-06-24T18:05:18.827906Z","steps":["trace[1224480506] 'agreement among raft nodes before linearized reading' (duration: 129.364071ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:20.451965Z","caller":"traceutil/trace.go:171","msg":"trace[1368206251] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"639.645461ms","start":"2026-06-24T18:05:19.812295Z","end":"2026-06-24T18:05:20.451941Z","steps":["trace[1368206251] 'process raft request' (duration: 639.491728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:05:20.452132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:05:19.812271Z","time spent":"639.764863ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:05:20.74339Z","caller":"traceutil/trace.go:171","msg":"trace[1195433030] linearizableReadLoop","detail":"{readStateIndex:5428; appliedIndex:5426; }","duration":"536.952776ms","start":"2026-06-24T18:05:20.206409Z","end":"2026-06-24T18:05:20.743361Z","steps":["trace[1195433030] 'read index received' (duration: 245.538405ms)","trace[1195433030] 'applied index is now lower than readState.Index' (duration: 291.413251ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:05:20.743425Z","caller":"traceutil/trace.go:171","msg":"trace[1016798596] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"910.805501ms","start":"2026-06-24T18:05:19.832583Z","end":"2026-06-24T18:05:20.743388Z","steps":["trace[1016798596] 'process raft request' (duration: 867.48494ms)","trace[1016798596] 'compare' (duration: 43.108947ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:05:20.743847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:05:19.832553Z","time spent":"911.215809ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:05:20.743916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.475875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-24T18:05:20.743655Z","caller":"traceutil/trace.go:171","msg":"trace[91244334] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"911.044915ms","start":"2026-06-24T18:05:19.832592Z","end":"2026-06-24T18:05:20.743637Z","steps":["trace[91244334] 'process raft request' (duration: 910.712009ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:20.744022Z","caller":"traceutil/trace.go:171","msg":"trace[1996727845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5235; }","duration":"537.603528ms","start":"2026-06-24T18:05:20.206395Z","end":"2026-06-24T18:05:20.743999Z","steps":["trace[1996727845] 'agreement among raft nodes before linearized reading' (duration: 537.297652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:05:20.74408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:05:19.832563Z","time spent":"911.488282ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:05:20.74409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:05:20.206334Z","time spent":"537.74233ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-24T18:05:20.763404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.267788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:05:20.763494Z","caller":"traceutil/trace.go:171","msg":"trace[1114144650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5236; }","duration":"484.407701ms","start":"2026-06-24T18:05:20.279065Z","end":"2026-06-24T18:05:20.763473Z","steps":["trace[1114144650] 'agreement among raft nodes before linearized reading' (duration: 484.236988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:05:20.763538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:05:20.279044Z","time spent":"484.485422ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T18:05:20.964421Z","caller":"traceutil/trace.go:171","msg":"trace[1247545783] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"129.414425ms","start":"2026-06-24T18:05:20.834949Z","end":"2026-06-24T18:05:20.964363Z","steps":["trace[1247545783] 'process raft request' (duration: 31.50244ms)","trace[1247545783] 'compare' (duration: 97.787343ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:05:20.999531Z","caller":"traceutil/trace.go:171","msg":"trace[757132548] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"164.45234ms","start":"2026-06-24T18:05:20.835058Z","end":"2026-06-24T18:05:20.99951Z","steps":["trace[757132548] 'process raft request' (duration: 164.304267ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:21.000414Z","caller":"traceutil/trace.go:171","msg":"trace[1637192917] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"165.236384ms","start":"2026-06-24T18:05:20.835132Z","end":"2026-06-24T18:05:21.000368Z","steps":["trace[1637192917] 'process raft request' (duration: 164.342947ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:34.992606Z","caller":"traceutil/trace.go:171","msg":"trace[991501422] linearizableReadLoop","detail":"{readStateIndex:5535; appliedIndex:5534; }","duration":"146.624273ms","start":"2026-06-24T18:05:34.845947Z","end":"2026-06-24T18:05:34.992571Z","steps":["trace[991501422] 'read index received' (duration: 85.193789ms)","trace[991501422] 'applied index is now lower than readState.Index' (duration: 61.428874ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:05:34.992724Z","caller":"traceutil/trace.go:171","msg":"trace[1135679533] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"184.189339ms","start":"2026-06-24T18:05:34.808493Z","end":"2026-06-24T18:05:34.992682Z","steps":["trace[1135679533] 'process raft request' (duration: 122.652663ms)","trace[1135679533] 'compare' (duration: 61.182099ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:05:34.99302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.05702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-24T18:05:34.993075Z","caller":"traceutil/trace.go:171","msg":"trace[887301844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5339; }","duration":"147.125431ms","start":"2026-06-24T18:05:34.845937Z","end":"2026-06-24T18:05:34.993063Z","steps":["trace[887301844] 'agreement among raft nodes before linearized reading' (duration: 146.839817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:05:34.993053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.238085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T18:05:34.993103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.412755ms","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-06-24T18:05:34.993133Z","caller":"traceutil/trace.go:171","msg":"trace[1884459314] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5339; }","duration":"135.371816ms","start":"2026-06-24T18:05:34.857742Z","end":"2026-06-24T18:05:34.993114Z","steps":["trace[1884459314] 'agreement among raft nodes before linearized reading' (duration: 135.215454ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:34.993166Z","caller":"traceutil/trace.go:171","msg":"trace[1391169306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5339; }","duration":"128.509516ms","start":"2026-06-24T18:05:34.864639Z","end":"2026-06-24T18:05:34.993149Z","steps":["trace[1391169306] 'agreement among raft nodes before linearized reading' (duration: 128.336163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:05:34.993134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.412892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:05:34.9933Z","caller":"traceutil/trace.go:171","msg":"trace[1149945175] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5339; }","duration":"133.621345ms","start":"2026-06-24T18:05:34.859652Z","end":"2026-06-24T18:05:34.993273Z","steps":["trace[1149945175] 'agreement among raft nodes before linearized reading' (duration: 133.35276ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:39.046948Z","caller":"traceutil/trace.go:171","msg":"trace[1434433857] linearizableReadLoop","detail":"{readStateIndex:5553; appliedIndex:5552; }","duration":"337.524808ms","start":"2026-06-24T18:05:38.709407Z","end":"2026-06-24T18:05:39.046932Z","steps":["trace[1434433857] 'read index received' (duration: 337.372285ms)","trace[1434433857] 'applied index is now lower than readState.Index' (duration: 151.543µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:05:39.04703Z","caller":"traceutil/trace.go:171","msg":"trace[1871231628] transaction","detail":"{read_only:false; response_revision:5356; number_of_response:1; }","duration":"535.308526ms","start":"2026-06-24T18:05:38.511709Z","end":"2026-06-24T18:05:39.047018Z","steps":["trace[1871231628] 'process raft request' (duration: 535.033581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:05:39.047355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:05:38.511678Z","time spent":"535.58745ms","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:05:39.047164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.742491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-24T18:05:39.047641Z","caller":"traceutil/trace.go:171","msg":"trace[500559681] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5356; }","duration":"338.25191ms","start":"2026-06-24T18:05:38.709373Z","end":"2026-06-24T18:05:39.047624Z","steps":["trace[500559681] 'agreement among raft nodes before linearized reading' (duration: 337.688331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:05:39.047778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:05:38.709356Z","time spent":"338.377762ms","remote":"127.0.0.1:52884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-24T18:05:39.075289Z","caller":"traceutil/trace.go:171","msg":"trace[2082555569] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"268.09398ms","start":"2026-06-24T18:05:38.807179Z","end":"2026-06-24T18:05:39.075273Z","steps":["trace[2082555569] 'process raft request' (duration: 267.961357ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:39.075259Z","caller":"traceutil/trace.go:171","msg":"trace[2095352069] transaction","detail":"{read_only:false; response_revision:5357; number_of_response:1; }","duration":"270.080734ms","start":"2026-06-24T18:05:38.805136Z","end":"2026-06-24T18:05:39.075217Z","steps":["trace[2095352069] 'process raft request' (duration: 269.84344ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:39.20464Z","caller":"traceutil/trace.go:171","msg":"trace[519086578] transaction","detail":"{read_only:false; response_revision:5360; number_of_response:1; }","duration":"123.944682ms","start":"2026-06-24T18:05:39.080665Z","end":"2026-06-24T18:05:39.20461Z","steps":["trace[519086578] 'process raft request' (duration: 39.180891ms)","trace[519086578] 'compare' (duration: 84.619979ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:05:39.240035Z","caller":"traceutil/trace.go:171","msg":"trace[1327829320] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"157.449934ms","start":"2026-06-24T18:05:39.082561Z","end":"2026-06-24T18:05:39.240011Z","steps":["trace[1327829320] 'process raft request' (duration: 157.267621ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:39.240223Z","caller":"traceutil/trace.go:171","msg":"trace[406229272] linearizableReadLoop","detail":"{readStateIndex:5558; appliedIndex:5556; }","duration":"156.748522ms","start":"2026-06-24T18:05:39.083443Z","end":"2026-06-24T18:05:39.240192Z","steps":["trace[406229272] 'read index received' (duration: 36.410443ms)","trace[406229272] 'applied index is now lower than readState.Index' (duration: 120.336049ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:05:39.240343Z","caller":"traceutil/trace.go:171","msg":"trace[1536815280] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"156.667561ms","start":"2026-06-24T18:05:39.083663Z","end":"2026-06-24T18:05:39.24033Z","steps":["trace[1536815280] 'process raft request' (duration: 156.310355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:05:39.2406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.943228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-24T18:05:39.240705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.20882ms","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-06-24T18:05:39.240653Z","caller":"traceutil/trace.go:171","msg":"trace[1663841536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5362; }","duration":"156.021929ms","start":"2026-06-24T18:05:39.084619Z","end":"2026-06-24T18:05:39.24064Z","steps":["trace[1663841536] 'agreement among raft nodes before linearized reading' (duration: 155.861916ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:39.240836Z","caller":"traceutil/trace.go:171","msg":"trace[1838493209] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5362; }","duration":"157.377743ms","start":"2026-06-24T18:05:39.083434Z","end":"2026-06-24T18:05:39.240812Z","steps":["trace[1838493209] 'agreement among raft nodes before linearized reading' (duration: 157.052377ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:06:08.421093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2905} {"level":"info","ts":"2026-06-24T18:06:08.542539Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2905,"took":"118.913107ms","hash":1786377727,"current-db-size-bytes":53522432,"current-db-size":"54 MB","current-db-size-in-use-bytes":28938240,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-24T18:06:08.542616Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1786377727,"revision":2905,"compact-revision":-1} {"level":"info","ts":"2026-06-24T18:08:15.499834Z","caller":"traceutil/trace.go:171","msg":"trace[259241731] transaction","detail":"{read_only:false; response_revision:6457; number_of_response:1; }","duration":"133.836998ms","start":"2026-06-24T18:08:15.365972Z","end":"2026-06-24T18:08:15.499809Z","steps":["trace[259241731] 'process raft request' (duration: 133.324941ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:08:22.217332Z","caller":"traceutil/trace.go:171","msg":"trace[842021087] transaction","detail":"{read_only:false; response_revision:6498; number_of_response:1; }","duration":"139.115913ms","start":"2026-06-24T18:08:22.078145Z","end":"2026-06-24T18:08:22.21726Z","steps":["trace[842021087] 'process raft request' (duration: 138.583445ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:08:22.22891Z","caller":"traceutil/trace.go:171","msg":"trace[2031616711] transaction","detail":"{read_only:false; response_revision:6499; number_of_response:1; }","duration":"148.74295ms","start":"2026-06-24T18:08:22.080119Z","end":"2026-06-24T18:08:22.228862Z","steps":["trace[2031616711] 'process raft request' (duration: 148.589678ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:08:22.229004Z","caller":"traceutil/trace.go:171","msg":"trace[573116721] linearizableReadLoop","detail":"{readStateIndex:6737; appliedIndex:6734; }","duration":"142.256641ms","start":"2026-06-24T18:08:22.086729Z","end":"2026-06-24T18:08:22.228986Z","steps":["trace[573116721] 'read index received' (duration: 130.133215ms)","trace[573116721] 'applied index is now lower than readState.Index' (duration: 12.121996ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:08:22.229166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.419694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-24T18:08:22.229452Z","caller":"traceutil/trace.go:171","msg":"trace[1750222686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6500; }","duration":"142.743699ms","start":"2026-06-24T18:08:22.086696Z","end":"2026-06-24T18:08:22.22944Z","steps":["trace[1750222686] 'agreement among raft nodes before linearized reading' (duration: 142.327052ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:08:22.229488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.180662ms","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-06-24T18:08:22.22952Z","caller":"traceutil/trace.go:171","msg":"trace[2192302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6500; }","duration":"135.239753ms","start":"2026-06-24T18:08:22.094271Z","end":"2026-06-24T18:08:22.229511Z","steps":["trace[2192302] 'agreement among raft nodes before linearized reading' (duration: 135.171372ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:08:22.229102Z","caller":"traceutil/trace.go:171","msg":"trace[1531277772] transaction","detail":"{read_only:false; response_revision:6500; number_of_response:1; }","duration":"148.560787ms","start":"2026-06-24T18:08:22.080454Z","end":"2026-06-24T18:08:22.229015Z","steps":["trace[1531277772] 'process raft request' (duration: 148.348044ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:08:22.717466Z","caller":"traceutil/trace.go:171","msg":"trace[1355018505] transaction","detail":"{read_only:false; response_revision:6503; number_of_response:1; }","duration":"161.381613ms","start":"2026-06-24T18:08:22.556026Z","end":"2026-06-24T18:08:22.717408Z","steps":["trace[1355018505] 'process raft request' (duration: 161.15939ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:08:49.506127Z","caller":"traceutil/trace.go:171","msg":"trace[677881258] transaction","detail":"{read_only:false; response_revision:6689; number_of_response:1; }","duration":"139.387516ms","start":"2026-06-24T18:08:49.366718Z","end":"2026-06-24T18:08:49.506105Z","steps":["trace[677881258] 'process raft request' (duration: 139.187703ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:11:08.426832Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5569} {"level":"info","ts":"2026-06-24T18:11:08.541259Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5569,"took":"112.690743ms","hash":388509310,"current-db-size-bytes":53522432,"current-db-size":"54 MB","current-db-size-in-use-bytes":18120704,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-24T18:11:08.54136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":388509310,"revision":5569,"compact-revision":2905}