{"level":"warn","ts":"2026-07-14T17:38:51.296094Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T17:38:51.296212Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.152: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.152:2380","--initial-cluster=instance=https://199.19.213.152:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.152:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.152: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-07-14T17:38:51.296316Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T17:38:51.29633Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.152:2380"]} {"level":"info","ts":"2026-07-14T17:38:51.296351Z","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-07-14T17:38:51.302477Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.152:2379"]} {"level":"info","ts":"2026-07-14T17:38:51.302626Z","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.152:2380"],"listen-peer-urls":["https://199.19.213.152:2380"],"advertise-client-urls":["https://199.19.213.152:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.152:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.152: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-07-14T17:38:51.308273Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.40215ms"} {"level":"info","ts":"2026-07-14T17:38:51.320569Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"68d6d88687e6b830","cluster-id":"8a5d84e8c26d99a1"} {"level":"info","ts":"2026-07-14T17:38:51.320728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T17:38:51.320781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became follower at term 0"} {"level":"info","ts":"2026-07-14T17:38:51.320841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 68d6d88687e6b830 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T17:38:51.32085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became follower at term 1"} {"level":"info","ts":"2026-07-14T17:38:51.320971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 switched to configuration voters=(7554463497277847600)"} {"level":"warn","ts":"2026-07-14T17:38:51.337731Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T17:38:51.34058Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T17:38:51.342254Z","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-07-14T17:38:51.345209Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"68d6d88687e6b830","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T17:38:51.345453Z","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-07-14T17:38:51.345557Z","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-07-14T17:38:51.345568Z","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-07-14T17:38:51.345557Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"68d6d88687e6b830","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T17:38:51.34842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 switched to configuration voters=(7554463497277847600)"} {"level":"info","ts":"2026-07-14T17:38:51.348715Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8a5d84e8c26d99a1","local-member-id":"68d6d88687e6b830","added-peer-id":"68d6d88687e6b830","added-peer-peer-urls":["https://199.19.213.152:2380"]} {"level":"info","ts":"2026-07-14T17:38:51.349805Z","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-07-14T17:38:51.350016Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.152:2380"} {"level":"info","ts":"2026-07-14T17:38:51.350132Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.152:2380"} {"level":"info","ts":"2026-07-14T17:38:51.35087Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"68d6d88687e6b830","initial-advertise-peer-urls":["https://199.19.213.152:2380"],"listen-peer-urls":["https://199.19.213.152:2380"],"advertise-client-urls":["https://199.19.213.152:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.152:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T17:38:51.350951Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T17:38:51.622509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T17:38:51.622588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T17:38:51.622684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 received MsgPreVoteResp from 68d6d88687e6b830 at term 1"} {"level":"info","ts":"2026-07-14T17:38:51.622801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became candidate at term 2"} {"level":"info","ts":"2026-07-14T17:38:51.62299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 received MsgVoteResp from 68d6d88687e6b830 at term 2"} {"level":"info","ts":"2026-07-14T17:38:51.623398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became leader at term 2"} {"level":"info","ts":"2026-07-14T17:38:51.623437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 68d6d88687e6b830 elected leader 68d6d88687e6b830 at term 2"} {"level":"info","ts":"2026-07-14T17:38:51.625162Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:38:51.625953Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"68d6d88687e6b830","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.152:2379]}","request-path":"/0/members/68d6d88687e6b830/attributes","cluster-id":"8a5d84e8c26d99a1","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T17:38:51.626048Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:38:51.626618Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8a5d84e8c26d99a1","local-member-id":"68d6d88687e6b830","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:38:51.626787Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:38:51.626906Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:38:51.627018Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:38:51.632672Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T17:38:51.632727Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T17:38:51.633153Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T17:38:51.633519Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.152:2379"} {"level":"info","ts":"2026-07-14T17:39:07.065425Z","caller":"traceutil/trace.go:171","msg":"trace[2100909324] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"109.622293ms","start":"2026-07-14T17:39:06.955775Z","end":"2026-07-14T17:39:07.065397Z","steps":["trace[2100909324] 'process raft request' (duration: 109.466387ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:07.25728Z","caller":"traceutil/trace.go:171","msg":"trace[1462568112] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"135.125517ms","start":"2026-07-14T17:39:07.122108Z","end":"2026-07-14T17:39:07.257233Z","steps":["trace[1462568112] 'process raft request' (duration: 134.407919ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:16.363623Z","caller":"traceutil/trace.go:171","msg":"trace[237767232] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"128.844288ms","start":"2026-07-14T17:39:16.234746Z","end":"2026-07-14T17:39:16.363591Z","steps":["trace[237767232] 'process raft request' (duration: 128.321678ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:17.747213Z","caller":"traceutil/trace.go:171","msg":"trace[42628324] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"157.864805ms","start":"2026-07-14T17:39:17.589319Z","end":"2026-07-14T17:39:17.747184Z","steps":["trace[42628324] 'read index received' (duration: 157.698358ms)","trace[42628324] 'applied index is now lower than readState.Index' (duration: 165.517µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:39:17.747294Z","caller":"traceutil/trace.go:171","msg":"trace[880228522] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"260.595949ms","start":"2026-07-14T17:39:17.486679Z","end":"2026-07-14T17:39:17.747275Z","steps":["trace[880228522] 'process raft request' (duration: 260.332569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:17.747607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.252329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T17:39:17.747774Z","caller":"traceutil/trace.go:171","msg":"trace[794154583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"158.482877ms","start":"2026-07-14T17:39:17.589256Z","end":"2026-07-14T17:39:17.747739Z","steps":["trace[794154583] 'agreement among raft nodes before linearized reading' (duration: 158.061632ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:30.090704Z","caller":"traceutil/trace.go:171","msg":"trace[756762071] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"178.395892ms","start":"2026-07-14T17:39:29.912249Z","end":"2026-07-14T17:39:30.090645Z","steps":["trace[756762071] 'process raft request' (duration: 159.525615ms)","trace[756762071] 'compare' (duration: 18.481733ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:39:30.091255Z","caller":"traceutil/trace.go:171","msg":"trace[1978764916] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"156.416141ms","start":"2026-07-14T17:39:29.934811Z","end":"2026-07-14T17:39:30.091227Z","steps":["trace[1978764916] 'process raft request' (duration: 156.09826ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:30.091954Z","caller":"traceutil/trace.go:171","msg":"trace[266769100] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"110.624085ms","start":"2026-07-14T17:39:29.98131Z","end":"2026-07-14T17:39:30.091934Z","steps":["trace[266769100] 'process raft request' (duration: 109.776195ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:30.091938Z","caller":"traceutil/trace.go:171","msg":"trace[211741576] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:553; }","duration":"133.069613ms","start":"2026-07-14T17:39:29.958821Z","end":"2026-07-14T17:39:30.09189Z","steps":["trace[211741576] 'read index received' (duration: 113.027754ms)","trace[211741576] 'applied index is now lower than readState.Index' (duration: 20.037899ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:39:30.0921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.248389ms","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-07-14T17:39:30.092146Z","caller":"traceutil/trace.go:171","msg":"trace[1815269421] 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:532; }","duration":"133.317581ms","start":"2026-07-14T17:39:29.958814Z","end":"2026-07-14T17:39:30.092132Z","steps":["trace[1815269421] 'agreement among raft nodes before linearized reading' (duration: 133.181427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:30.092179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.257529ms","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-07-14T17:39:30.092331Z","caller":"traceutil/trace.go:171","msg":"trace[543841512] 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:532; }","duration":"133.412854ms","start":"2026-07-14T17:39:29.958893Z","end":"2026-07-14T17:39:30.092305Z","steps":["trace[543841512] 'agreement among raft nodes before linearized reading' (duration: 133.225298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:30.093034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.814684ms","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-07-14T17:39:30.093067Z","caller":"traceutil/trace.go:171","msg":"trace[1284504028] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:532; }","duration":"114.855085ms","start":"2026-07-14T17:39:29.9782Z","end":"2026-07-14T17:39:30.093055Z","steps":["trace[1284504028] 'agreement among raft nodes before linearized reading' (duration: 114.802853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:30.093076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.881106ms","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-07-14T17:39:30.093119Z","caller":"traceutil/trace.go:171","msg":"trace[885679368] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:532; }","duration":"114.956379ms","start":"2026-07-14T17:39:29.978151Z","end":"2026-07-14T17:39:30.093107Z","steps":["trace[885679368] 'agreement among raft nodes before linearized reading' (duration: 114.889076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:30.093217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.232123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:39:30.093243Z","caller":"traceutil/trace.go:171","msg":"trace[1837416958] 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:532; }","duration":"134.274265ms","start":"2026-07-14T17:39:29.958961Z","end":"2026-07-14T17:39:30.093236Z","steps":["trace[1837416958] 'agreement among raft nodes before linearized reading' (duration: 134.229694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:30.094104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.274707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:39:30.09414Z","caller":"traceutil/trace.go:171","msg":"trace[1724451491] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:532; }","duration":"100.328139ms","start":"2026-07-14T17:39:29.993801Z","end":"2026-07-14T17:39:30.094129Z","steps":["trace[1724451491] 'agreement among raft nodes before linearized reading' (duration: 100.269837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:30.094179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.936953ms","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-07-14T17:39:30.094311Z","caller":"traceutil/trace.go:171","msg":"trace[579277065] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:532; }","duration":"115.992836ms","start":"2026-07-14T17:39:29.978218Z","end":"2026-07-14T17:39:30.09421Z","steps":["trace[579277065] 'agreement among raft nodes before linearized reading' (duration: 115.920253ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:30.38962Z","caller":"traceutil/trace.go:171","msg":"trace[1393581952] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"244.35819ms","start":"2026-07-14T17:39:30.145232Z","end":"2026-07-14T17:39:30.38959Z","steps":["trace[1393581952] 'process raft request' (duration: 236.009875ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:30.389993Z","caller":"traceutil/trace.go:171","msg":"trace[1965983500] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"239.290861ms","start":"2026-07-14T17:39:30.150687Z","end":"2026-07-14T17:39:30.389978Z","steps":["trace[1965983500] 'process raft request' (duration: 238.718871ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:30.390977Z","caller":"traceutil/trace.go:171","msg":"trace[1904380376] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"198.889052ms","start":"2026-07-14T17:39:30.192059Z","end":"2026-07-14T17:39:30.390948Z","steps":["trace[1904380376] 'process raft request' (duration: 197.583186ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:30.399161Z","caller":"traceutil/trace.go:171","msg":"trace[1555576181] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:559; }","duration":"122.054596ms","start":"2026-07-14T17:39:30.27709Z","end":"2026-07-14T17:39:30.399144Z","steps":["trace[1555576181] 'read index received' (duration: 103.733068ms)","trace[1555576181] 'applied index is now lower than readState.Index' (duration: 18.320398ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:39:30.39929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.17735ms","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-07-14T17:39:30.399313Z","caller":"traceutil/trace.go:171","msg":"trace[824930903] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:538; }","duration":"122.243022ms","start":"2026-07-14T17:39:30.277062Z","end":"2026-07-14T17:39:30.399305Z","steps":["trace[824930903] 'agreement among raft nodes before linearized reading' (duration: 122.17487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:30.399453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.236262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:39:30.399468Z","caller":"traceutil/trace.go:171","msg":"trace[642365158] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:538; }","duration":"122.304284ms","start":"2026-07-14T17:39:30.277158Z","end":"2026-07-14T17:39:30.399462Z","steps":["trace[642365158] 'agreement among raft nodes before linearized reading' (duration: 122.276353ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:31.53867Z","caller":"traceutil/trace.go:171","msg":"trace[1865415243] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"103.836465ms","start":"2026-07-14T17:39:31.434794Z","end":"2026-07-14T17:39:31.538631Z","steps":["trace[1865415243] 'process raft request' (duration: 103.320477ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:34.658241Z","caller":"traceutil/trace.go:171","msg":"trace[444524688] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"101.024659ms","start":"2026-07-14T17:39:34.557185Z","end":"2026-07-14T17:39:34.65821Z","steps":["trace[444524688] 'process raft request' (duration: 97.87327ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:36.770993Z","caller":"traceutil/trace.go:171","msg":"trace[2072903721] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"107.135339ms","start":"2026-07-14T17:39:36.663827Z","end":"2026-07-14T17:39:36.770963Z","steps":["trace[2072903721] 'read index received' (duration: 106.163696ms)","trace[2072903721] 'applied index is now lower than readState.Index' (duration: 970.573µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:39:36.771108Z","caller":"traceutil/trace.go:171","msg":"trace[2091432347] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"272.72202ms","start":"2026-07-14T17:39:36.498335Z","end":"2026-07-14T17:39:36.771057Z","steps":["trace[2091432347] 'process raft request' (duration: 272.019906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:36.772126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.284829ms","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-07-14T17:39:36.772192Z","caller":"traceutil/trace.go:171","msg":"trace[1193867151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"108.577098ms","start":"2026-07-14T17:39:36.663586Z","end":"2026-07-14T17:39:36.772163Z","steps":["trace[1193867151] 'agreement among raft nodes before linearized reading' (duration: 107.507572ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:37.234575Z","caller":"traceutil/trace.go:171","msg":"trace[1941667766] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"454.148121ms","start":"2026-07-14T17:39:36.78039Z","end":"2026-07-14T17:39:37.234538Z","steps":["trace[1941667766] 'process raft request' (duration: 436.441464ms)","trace[1941667766] 'compare' (duration: 17.522161ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:39:37.235394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:36.780361Z","time spent":"454.313927ms","remote":"127.0.0.1:58460","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-07-14T17:39:37.242318Z","caller":"traceutil/trace.go:171","msg":"trace[119730236] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"165.005727ms","start":"2026-07-14T17:39:37.077277Z","end":"2026-07-14T17:39:37.242283Z","steps":["trace[119730236] 'read index received' (duration: 139.677408ms)","trace[119730236] 'applied index is now lower than readState.Index' (duration: 25.327069ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:39:37.242493Z","caller":"traceutil/trace.go:171","msg":"trace[684097227] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"375.403378ms","start":"2026-07-14T17:39:36.867032Z","end":"2026-07-14T17:39:37.242435Z","steps":["trace[684097227] 'process raft request' (duration: 375.064776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:37.242632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.341168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8x45j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-14T17:39:37.242706Z","caller":"traceutil/trace.go:171","msg":"trace[1956528646] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8x45j; range_end:; response_count:1; response_revision:595; }","duration":"165.462533ms","start":"2026-07-14T17:39:37.077224Z","end":"2026-07-14T17:39:37.242686Z","steps":["trace[1956528646] 'agreement among raft nodes before linearized reading' (duration: 165.203124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:37.242715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:36.867002Z","time spent":"375.610395ms","remote":"127.0.0.1:58704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:39:37.242953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.240775ms","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-07-14T17:39:37.24304Z","caller":"traceutil/trace.go:171","msg":"trace[529894407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"165.425491ms","start":"2026-07-14T17:39:37.077592Z","end":"2026-07-14T17:39:37.243018Z","steps":["trace[529894407] 'agreement among raft nodes before linearized reading' (duration: 164.928864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:37.846849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13272283343871360523,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:39:38.199279Z","caller":"traceutil/trace.go:171","msg":"trace[877969197] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"935.51927ms","start":"2026-07-14T17:39:37.263716Z","end":"2026-07-14T17:39:38.199235Z","steps":["trace[877969197] 'process raft request' (duration: 932.147555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:38.199472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:37.263692Z","time spent":"935.683426ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:39:38.211058Z","caller":"traceutil/trace.go:171","msg":"trace[178487026] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"864.95238ms","start":"2026-07-14T17:39:37.346082Z","end":"2026-07-14T17:39:38.211034Z","steps":["trace[178487026] 'read index received' (duration: 849.836664ms)","trace[178487026] 'applied index is now lower than readState.Index' (duration: 15.114746ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:39:38.211118Z","caller":"traceutil/trace.go:171","msg":"trace[633570439] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"556.898669ms","start":"2026-07-14T17:39:37.654176Z","end":"2026-07-14T17:39:38.211075Z","steps":["trace[633570439] 'process raft request' (duration: 556.232646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:38.211241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.148836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:39:38.211273Z","caller":"traceutil/trace.go:171","msg":"trace[451909740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"865.253719ms","start":"2026-07-14T17:39:37.34601Z","end":"2026-07-14T17:39:38.211263Z","steps":["trace[451909740] 'agreement among raft nodes before linearized reading' (duration: 865.136295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:38.211299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:37.345979Z","time spent":"865.314772ms","remote":"127.0.0.1:58278","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-07-14T17:39:38.211349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:37.654134Z","time spent":"557.080746ms","remote":"127.0.0.1:58348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:39:38.211475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.533115ms","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-07-14T17:39:38.21151Z","caller":"traceutil/trace.go:171","msg":"trace[1523012873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"219.615258ms","start":"2026-07-14T17:39:37.991885Z","end":"2026-07-14T17:39:38.2115Z","steps":["trace[1523012873] 'agreement among raft nodes before linearized reading' (duration: 219.497044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:38.211677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.302414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T17:39:38.211947Z","caller":"traceutil/trace.go:171","msg":"trace[1858006508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"249.482601ms","start":"2026-07-14T17:39:37.962272Z","end":"2026-07-14T17:39:38.211755Z","steps":["trace[1858006508] 'agreement among raft nodes before linearized reading' (duration: 249.000985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:39.378396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.760118ms","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-07-14T17:39:39.378471Z","caller":"traceutil/trace.go:171","msg":"trace[1730095591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"106.921933ms","start":"2026-07-14T17:39:39.27153Z","end":"2026-07-14T17:39:39.378452Z","steps":["trace[1730095591] 'range keys from in-memory index tree' (duration: 106.424886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:39.378441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.445351ms","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-07-14T17:39:39.37854Z","caller":"traceutil/trace.go:171","msg":"trace[1839074250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"137.657028ms","start":"2026-07-14T17:39:39.240861Z","end":"2026-07-14T17:39:39.378518Z","steps":["trace[1839074250] 'range keys from in-memory index tree' (duration: 137.164451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:54.704568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13272283343871360830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:39:55.205463Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13272283343871360830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:39:55.552084Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.350531802s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T17:39:55.552568Z","caller":"traceutil/trace.go:171","msg":"trace[947443436] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"1.351118061s","start":"2026-07-14T17:39:54.201426Z","end":"2026-07-14T17:39:55.552544Z","steps":["trace[947443436] 'process raft request' (duration: 1.350938265s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:55.552733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:54.201404Z","time spent":"1.351230625s","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:39:55.662853Z","caller":"traceutil/trace.go:171","msg":"trace[2061533741] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:789; }","duration":"1.458358517s","start":"2026-07-14T17:39:54.204459Z","end":"2026-07-14T17:39:55.662818Z","steps":["trace[2061533741] 'read index received' (duration: 1.34794408s)","trace[2061533741] 'applied index is now lower than readState.Index' (duration: 110.413007ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:39:55.662993Z","caller":"traceutil/trace.go:171","msg":"trace[1569218710] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"700.649024ms","start":"2026-07-14T17:39:54.96231Z","end":"2026-07-14T17:39:55.662959Z","steps":["trace[1569218710] 'process raft request' (duration: 699.031062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:55.663311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:54.962245Z","time spent":"700.966284ms","remote":"127.0.0.1:58460","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-07-14T17:39:55.663347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339478497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-14T17:39:55.663344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317723143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:39:55.663392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.087065ms","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-07-14T17:39:55.663407Z","caller":"traceutil/trace.go:171","msg":"trace[1145556805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"1.317818917s","start":"2026-07-14T17:39:54.345572Z","end":"2026-07-14T17:39:55.663391Z","steps":["trace[1145556805] 'agreement among raft nodes before linearized reading' (duration: 1.317725134s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:55.663414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.082884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.152\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-14T17:39:55.663447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:54.345561Z","time spent":"1.317878928s","remote":"127.0.0.1:58280","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-07-14T17:39:55.663465Z","caller":"traceutil/trace.go:171","msg":"trace[738763293] range","detail":"{range_begin:/registry/masterleases/199.19.213.152; range_end:; response_count:1; response_revision:761; }","duration":"657.210177ms","start":"2026-07-14T17:39:55.006243Z","end":"2026-07-14T17:39:55.663453Z","steps":["trace[738763293] 'agreement among raft nodes before linearized reading' (duration: 656.98985ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:55.663471Z","caller":"traceutil/trace.go:171","msg":"trace[697735979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"160.177909ms","start":"2026-07-14T17:39:55.503252Z","end":"2026-07-14T17:39:55.66343Z","steps":["trace[697735979] 'agreement among raft nodes before linearized reading' (duration: 160.005383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:55.663504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:55.006222Z","time spent":"657.27426ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.152\" "} {"level":"warn","ts":"2026-07-14T17:39:55.66352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332531445s","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-07-14T17:39:55.663559Z","caller":"traceutil/trace.go:171","msg":"trace[2134869936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"1.332593657s","start":"2026-07-14T17:39:54.330954Z","end":"2026-07-14T17:39:55.663548Z","steps":["trace[2134869936] 'agreement among raft nodes before linearized reading' (duration: 1.332470734s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:55.663619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:54.330937Z","time spent":"1.332671659s","remote":"127.0.0.1:58460","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-07-14T17:39:55.663067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458581375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T17:39:55.663671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.53438ms","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-07-14T17:39:55.66369Z","caller":"traceutil/trace.go:171","msg":"trace[1195071474] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:761; }","duration":"1.459225375s","start":"2026-07-14T17:39:54.204445Z","end":"2026-07-14T17:39:55.663671Z","steps":["trace[1195071474] 'agreement among raft nodes before linearized reading' (duration: 1.458530973s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:55.663402Z","caller":"traceutil/trace.go:171","msg":"trace[284212421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:761; }","duration":"1.339552949s","start":"2026-07-14T17:39:54.323829Z","end":"2026-07-14T17:39:55.663382Z","steps":["trace[284212421] 'agreement among raft nodes before linearized reading' (duration: 1.339385814s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:55.663733Z","caller":"traceutil/trace.go:171","msg":"trace[390709626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:761; }","duration":"177.637603ms","start":"2026-07-14T17:39:55.486081Z","end":"2026-07-14T17:39:55.663719Z","steps":["trace[390709626] 'agreement among raft nodes before linearized reading' (duration: 177.496538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:39:55.66375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:54.204401Z","time spent":"1.459328488s","remote":"127.0.0.1:58622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:39:55.663754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:39:54.323816Z","time spent":"1.339927882s","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T17:40:19.441547Z","caller":"traceutil/trace.go:171","msg":"trace[1217392443] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"111.744781ms","start":"2026-07-14T17:40:19.329769Z","end":"2026-07-14T17:40:19.441514Z","steps":["trace[1217392443] 'process raft request' (duration: 111.602797ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:19.441731Z","caller":"traceutil/trace.go:171","msg":"trace[301992559] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"112.512693ms","start":"2026-07-14T17:40:19.329178Z","end":"2026-07-14T17:40:19.44169Z","steps":["trace[301992559] 'process raft request' (duration: 99.794006ms)","trace[301992559] 'compare' (duration: 12.245464ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:19.667137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.522522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:19.667458Z","caller":"traceutil/trace.go:171","msg":"trace[575246664] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"108.686342ms","start":"2026-07-14T17:40:19.558727Z","end":"2026-07-14T17:40:19.667413Z","steps":["trace[575246664] 'process raft request' (duration: 108.569349ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:19.667477Z","caller":"traceutil/trace.go:171","msg":"trace[1375322265] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"217.276983ms","start":"2026-07-14T17:40:19.450161Z","end":"2026-07-14T17:40:19.667438Z","steps":["trace[1375322265] 'process raft request' (duration: 93.134814ms)","trace[1375322265] 'compare' (duration: 123.14663ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:50.473298Z","caller":"traceutil/trace.go:171","msg":"trace[2122926399] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1378; }","duration":"117.909559ms","start":"2026-07-14T17:40:50.355363Z","end":"2026-07-14T17:40:50.473273Z","steps":["trace[2122926399] 'read index received' (duration: 8.727596ms)","trace[2122926399] 'applied index is now lower than readState.Index' (duration: 109.180813ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:50.473381Z","caller":"traceutil/trace.go:171","msg":"trace[1452558980] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"118.431404ms","start":"2026-07-14T17:40:50.354884Z","end":"2026-07-14T17:40:50.473316Z","steps":["trace[1452558980] 'process raft request' (duration: 113.149886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:50.508922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.533094ms","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-07-14T17:40:50.509022Z","caller":"traceutil/trace.go:171","msg":"trace[134137096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1333; }","duration":"153.640027ms","start":"2026-07-14T17:40:50.355349Z","end":"2026-07-14T17:40:50.508989Z","steps":["trace[134137096] 'agreement among raft nodes before linearized reading' (duration: 118.059074ms)","trace[134137096] 'range keys from in-memory index tree' (duration: 35.327206ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:50.509278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.801209ms","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-07-14T17:40:50.509376Z","caller":"traceutil/trace.go:171","msg":"trace[257475965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1333; }","duration":"152.130128ms","start":"2026-07-14T17:40:50.357221Z","end":"2026-07-14T17:40:50.509351Z","steps":["trace[257475965] 'agreement among raft nodes before linearized reading' (duration: 151.681606ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:50.6241Z","caller":"traceutil/trace.go:171","msg":"trace[1690340489] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"106.622537ms","start":"2026-07-14T17:40:50.517456Z","end":"2026-07-14T17:40:50.624079Z","steps":["trace[1690340489] 'process raft request' (duration: 106.531194ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:50.624182Z","caller":"traceutil/trace.go:171","msg":"trace[926582576] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"110.262731ms","start":"2026-07-14T17:40:50.513892Z","end":"2026-07-14T17:40:50.624155Z","steps":["trace[926582576] 'process raft request' (duration: 81.818712ms)","trace[926582576] 'compare' (duration: 28.065279ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:54.94103Z","caller":"traceutil/trace.go:171","msg":"trace[1588640001] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"252.922269ms","start":"2026-07-14T17:40:54.688081Z","end":"2026-07-14T17:40:54.941003Z","steps":["trace[1588640001] 'process raft request' (duration: 252.758515ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:55.688591Z","caller":"traceutil/trace.go:171","msg":"trace[709194732] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"133.207422ms","start":"2026-07-14T17:40:55.555361Z","end":"2026-07-14T17:40:55.688568Z","steps":["trace[709194732] 'process raft request' (duration: 133.042408ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:57.614793Z","caller":"traceutil/trace.go:171","msg":"trace[882176509] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"131.198989ms","start":"2026-07-14T17:40:57.483569Z","end":"2026-07-14T17:40:57.614768Z","steps":["trace[882176509] 'process raft request' (duration: 131.030024ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:01.165681Z","caller":"traceutil/trace.go:171","msg":"trace[1259057277] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"125.800422ms","start":"2026-07-14T17:41:01.039859Z","end":"2026-07-14T17:41:01.165659Z","steps":["trace[1259057277] 'process raft request' (duration: 125.543616ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:06.426515Z","caller":"traceutil/trace.go:171","msg":"trace[814596347] linearizableReadLoop","detail":"{readStateIndex:1633; appliedIndex:1632; }","duration":"107.250367ms","start":"2026-07-14T17:41:06.319239Z","end":"2026-07-14T17:41:06.42649Z","steps":["trace[814596347] 'read index received' (duration: 106.899508ms)","trace[814596347] 'applied index is now lower than readState.Index' (duration: 349.729µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:06.426615Z","caller":"traceutil/trace.go:171","msg":"trace[1570909245] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"240.637048ms","start":"2026-07-14T17:41:06.185941Z","end":"2026-07-14T17:41:06.426578Z","steps":["trace[1570909245] 'process raft request' (duration: 240.29085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:06.426776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.526273ms","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-07-14T17:41:06.426812Z","caller":"traceutil/trace.go:171","msg":"trace[1158997129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1565; }","duration":"107.622836ms","start":"2026-07-14T17:41:06.319179Z","end":"2026-07-14T17:41:06.426802Z","steps":["trace[1158997129] 'agreement among raft nodes before linearized reading' (duration: 107.457012ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:06.550856Z","caller":"traceutil/trace.go:171","msg":"trace[1482285614] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"116.782371ms","start":"2026-07-14T17:41:06.434036Z","end":"2026-07-14T17:41:06.550819Z","steps":["trace[1482285614] 'process raft request' (duration: 111.46005ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:06.551299Z","caller":"traceutil/trace.go:171","msg":"trace[1904634756] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"109.294637ms","start":"2026-07-14T17:41:06.441936Z","end":"2026-07-14T17:41:06.551231Z","steps":["trace[1904634756] 'process raft request' (duration: 108.804634ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:06.551513Z","caller":"traceutil/trace.go:171","msg":"trace[1912406200] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"109.473731ms","start":"2026-07-14T17:41:06.442007Z","end":"2026-07-14T17:41:06.551481Z","steps":["trace[1912406200] 'process raft request' (duration: 109.105172ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:07.216714Z","caller":"traceutil/trace.go:171","msg":"trace[528487812] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"144.110846ms","start":"2026-07-14T17:41:07.072566Z","end":"2026-07-14T17:41:07.216677Z","steps":["trace[528487812] 'process raft request' (duration: 109.094004ms)","trace[528487812] 'compare' (duration: 34.786686ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:07.216774Z","caller":"traceutil/trace.go:171","msg":"trace[1165038737] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"136.96486ms","start":"2026-07-14T17:41:07.079781Z","end":"2026-07-14T17:41:07.216746Z","steps":["trace[1165038737] 'process raft request' (duration: 136.824676ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:07.517251Z","caller":"traceutil/trace.go:171","msg":"trace[1681498141] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"288.471047ms","start":"2026-07-14T17:41:07.228745Z","end":"2026-07-14T17:41:07.517216Z","steps":["trace[1681498141] 'process raft request' (duration: 288.309603ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:07.729427Z","caller":"traceutil/trace.go:171","msg":"trace[830954260] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"408.678185ms","start":"2026-07-14T17:41:07.320717Z","end":"2026-07-14T17:41:07.729395Z","steps":["trace[830954260] 'process raft request' (duration: 408.439399ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:07.729477Z","caller":"traceutil/trace.go:171","msg":"trace[1434381327] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"372.547606ms","start":"2026-07-14T17:41:07.356905Z","end":"2026-07-14T17:41:07.729453Z","steps":["trace[1434381327] 'process raft request' (duration: 372.489614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:07.729597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:07.3207Z","time spent":"408.804238ms","remote":"127.0.0.1:58698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24969,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:07.729866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:07.356893Z","time spent":"372.668928ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:07.730176Z","caller":"traceutil/trace.go:171","msg":"trace[1299856717] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"398.371941ms","start":"2026-07-14T17:41:07.331792Z","end":"2026-07-14T17:41:07.730164Z","steps":["trace[1299856717] 'process raft request' (duration: 397.51823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:07.730264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:07.331783Z","time spent":"398.431942ms","remote":"127.0.0.1:46030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3396,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:07.730683Z","caller":"traceutil/trace.go:171","msg":"trace[1816827721] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1654; }","duration":"397.295775ms","start":"2026-07-14T17:41:07.333376Z","end":"2026-07-14T17:41:07.730672Z","steps":["trace[1816827721] 'process raft request' (duration: 395.986172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:07.730753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:07.333368Z","time spent":"397.352786ms","remote":"127.0.0.1:46030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97,"response count":0,"response size":3445,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:09.830937Z","caller":"traceutil/trace.go:171","msg":"trace[774861422] linearizableReadLoop","detail":"{readStateIndex:1749; appliedIndex:1748; }","duration":"228.776145ms","start":"2026-07-14T17:41:09.602126Z","end":"2026-07-14T17:41:09.830902Z","steps":["trace[774861422] 'read index received' (duration: 189.30744ms)","trace[774861422] 'applied index is now lower than readState.Index' (duration: 39.467245ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:09.830979Z","caller":"traceutil/trace.go:171","msg":"trace[2033767865] transaction","detail":"{read_only:false; response_revision:1679; number_of_response:1; }","duration":"386.531043ms","start":"2026-07-14T17:41:09.444394Z","end":"2026-07-14T17:41:09.830925Z","steps":["trace[2033767865] 'process raft request' (duration: 347.070638ms)","trace[2033767865] 'compare' (duration: 38.959392ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:09.831116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.984461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:09.831227Z","caller":"traceutil/trace.go:171","msg":"trace[1879214349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1679; }","duration":"229.132954ms","start":"2026-07-14T17:41:09.602034Z","end":"2026-07-14T17:41:09.831167Z","steps":["trace[1879214349] 'agreement among raft nodes before linearized reading' (duration: 228.98109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:09.83124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:09.444363Z","time spent":"386.724608ms","remote":"127.0.0.1:58314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:09.869846Z","caller":"traceutil/trace.go:171","msg":"trace[993720012] transaction","detail":"{read_only:false; response_revision:1680; number_of_response:1; }","duration":"107.317904ms","start":"2026-07-14T17:41:09.762503Z","end":"2026-07-14T17:41:09.869821Z","steps":["trace[993720012] 'process raft request' (duration: 107.180731ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:10.094437Z","caller":"traceutil/trace.go:171","msg":"trace[640510029] transaction","detail":"{read_only:false; response_revision:1683; number_of_response:1; }","duration":"113.313703ms","start":"2026-07-14T17:41:09.981097Z","end":"2026-07-14T17:41:10.094411Z","steps":["trace[640510029] 'process raft request' (duration: 112.960435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:10.578733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.630012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:10.578831Z","caller":"traceutil/trace.go:171","msg":"trace[1243136330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1683; }","duration":"232.776806ms","start":"2026-07-14T17:41:10.346032Z","end":"2026-07-14T17:41:10.578809Z","steps":["trace[1243136330] 'range keys from in-memory index tree' (duration: 232.554931ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:10.579995Z","caller":"traceutil/trace.go:171","msg":"trace[2070729949] linearizableReadLoop","detail":"{readStateIndex:1754; appliedIndex:1753; }","duration":"139.220624ms","start":"2026-07-14T17:41:10.440746Z","end":"2026-07-14T17:41:10.579967Z","steps":["trace[2070729949] 'read index received' (duration: 138.838475ms)","trace[2070729949] 'applied index is now lower than readState.Index' (duration: 381.019µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:10.580114Z","caller":"traceutil/trace.go:171","msg":"trace[1364371356] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"207.88621ms","start":"2026-07-14T17:41:10.372215Z","end":"2026-07-14T17:41:10.580101Z","steps":["trace[1364371356] 'process raft request' (duration: 207.436369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:10.580482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.709697ms","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-07-14T17:41:10.580584Z","caller":"traceutil/trace.go:171","msg":"trace[1487310453] 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:1684; }","duration":"139.878201ms","start":"2026-07-14T17:41:10.440681Z","end":"2026-07-14T17:41:10.580559Z","steps":["trace[1487310453] 'agreement among raft nodes before linearized reading' (duration: 139.718447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:10.701148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.632648ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-14T17:41:10.701532Z","caller":"traceutil/trace.go:171","msg":"trace[251243322] linearizableReadLoop","detail":"{readStateIndex:1755; appliedIndex:1754; }","duration":"121.435661ms","start":"2026-07-14T17:41:10.580064Z","end":"2026-07-14T17:41:10.701499Z","steps":["trace[251243322] 'read index received' (duration: 12.360681ms)","trace[251243322] 'applied index is now lower than readState.Index' (duration: 109.07107ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:10.701731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.475267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T17:41:10.701821Z","caller":"traceutil/trace.go:171","msg":"trace[388422250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1684; }","duration":"129.6ms","start":"2026-07-14T17:41:10.572202Z","end":"2026-07-14T17:41:10.701802Z","steps":["trace[388422250] 'agreement among raft nodes before linearized reading' (duration: 129.314613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:10.701837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.19397ms","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-07-14T17:41:10.701878Z","caller":"traceutil/trace.go:171","msg":"trace[920525288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1684; }","duration":"193.322564ms","start":"2026-07-14T17:41:10.508543Z","end":"2026-07-14T17:41:10.701865Z","steps":["trace[920525288] 'agreement among raft nodes before linearized reading' (duration: 193.032397ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:12.882702Z","caller":"traceutil/trace.go:171","msg":"trace[104690672] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1700; }","duration":"388.124508ms","start":"2026-07-14T17:41:12.494557Z","end":"2026-07-14T17:41:12.882681Z","steps":["trace[104690672] 'process raft request' (duration: 387.980365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:12.882822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.494534Z","time spent":"388.2332ms","remote":"127.0.0.1:58314","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:41:13.253325Z","caller":"traceutil/trace.go:171","msg":"trace[162819546] linearizableReadLoop","detail":"{readStateIndex:1775; appliedIndex:1773; }","duration":"533.288569ms","start":"2026-07-14T17:41:12.720018Z","end":"2026-07-14T17:41:13.253306Z","steps":["trace[162819546] 'read index received' (duration: 162.459798ms)","trace[162819546] 'applied index is now lower than readState.Index' (duration: 370.828081ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:13.253405Z","caller":"traceutil/trace.go:171","msg":"trace[849267590] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"673.561098ms","start":"2026-07-14T17:41:12.579837Z","end":"2026-07-14T17:41:13.253398Z","steps":["trace[849267590] 'process raft request' (duration: 673.334043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.253533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.579802Z","time spent":"673.656101ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":805,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:41:13.254478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.434327ms","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-07-14T17:41:13.254509Z","caller":"traceutil/trace.go:171","msg":"trace[721223824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1701; }","duration":"534.515868ms","start":"2026-07-14T17:41:12.719985Z","end":"2026-07-14T17:41:13.254501Z","steps":["trace[721223824] 'agreement among raft nodes before linearized reading' (duration: 534.348724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.254531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.719965Z","time spent":"534.560019ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T17:41:13.254748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.044865ms","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-07-14T17:41:13.254774Z","caller":"traceutil/trace.go:171","msg":"trace[1001611404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1701; }","duration":"484.091496ms","start":"2026-07-14T17:41:12.770675Z","end":"2026-07-14T17:41:13.254766Z","steps":["trace[1001611404] 'agreement among raft nodes before linearized reading' (duration: 484.007404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.254818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.770659Z","time spent":"484.149887ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T17:41:13.25508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.048093ms","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-07-14T17:41:13.255123Z","caller":"traceutil/trace.go:171","msg":"trace[1780847169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1701; }","duration":"113.156285ms","start":"2026-07-14T17:41:13.141959Z","end":"2026-07-14T17:41:13.255116Z","steps":["trace[1780847169] 'agreement among raft nodes before linearized reading' (duration: 113.053472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.255259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.515836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T17:41:13.255326Z","caller":"traceutil/trace.go:171","msg":"trace[565414578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1701; }","duration":"484.603438ms","start":"2026-07-14T17:41:12.770708Z","end":"2026-07-14T17:41:13.255311Z","steps":["trace[565414578] 'agreement among raft nodes before linearized reading' (duration: 484.386683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.255371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.770705Z","time spent":"484.649129ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:41:13.255474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.58954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com\" ","response":"range_response_count:1 size:187546"} {"level":"info","ts":"2026-07-14T17:41:13.255535Z","caller":"traceutil/trace.go:171","msg":"trace[486354633] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com; range_end:; response_count:1; response_revision:1701; }","duration":"371.686091ms","start":"2026-07-14T17:41:12.883834Z","end":"2026-07-14T17:41:13.25552Z","steps":["trace[486354633] 'agreement among raft nodes before linearized reading' (duration: 371.448136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.25557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.883821Z","time spent":"371.743343ms","remote":"127.0.0.1:58314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":187569,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com\" "} {"level":"warn","ts":"2026-07-14T17:41:13.473441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.809304ms","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-07-14T17:41:13.473527Z","caller":"traceutil/trace.go:171","msg":"trace[1702642595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1701; }","duration":"674.901806ms","start":"2026-07-14T17:41:12.798602Z","end":"2026-07-14T17:41:13.473504Z","steps":["trace[1702642595] 'agreement among raft nodes before linearized reading' (duration: 457.096052ms)","trace[1702642595] 'range keys from in-memory index tree' (duration: 217.6028ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:13.473569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.798568Z","time spent":"674.993168ms","remote":"127.0.0.1:58460","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-07-14T17:41:13.474262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.521779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:41:13.474637Z","caller":"traceutil/trace.go:171","msg":"trace[313450077] linearizableReadLoop","detail":"{readStateIndex:1780; appliedIndex:1775; }","duration":"128.530716ms","start":"2026-07-14T17:41:13.346089Z","end":"2026-07-14T17:41:13.47462Z","steps":["trace[313450077] 'read index received' (duration: 115.192294ms)","trace[313450077] 'applied index is now lower than readState.Index' (duration: 13.337502ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:13.474818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.727232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:13.47489Z","caller":"traceutil/trace.go:171","msg":"trace[1174287369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1706; }","duration":"128.807603ms","start":"2026-07-14T17:41:13.346042Z","end":"2026-07-14T17:41:13.47485Z","steps":["trace[1174287369] 'agreement among raft nodes before linearized reading' (duration: 128.70008ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:13.475004Z","caller":"traceutil/trace.go:171","msg":"trace[1739095954] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"212.755582ms","start":"2026-07-14T17:41:13.262219Z","end":"2026-07-14T17:41:13.474974Z","steps":["trace[1739095954] 'process raft request' (duration: 212.219399ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:13.475166Z","caller":"traceutil/trace.go:171","msg":"trace[289722306] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"212.22681ms","start":"2026-07-14T17:41:13.262918Z","end":"2026-07-14T17:41:13.475144Z","steps":["trace[289722306] 'process raft request' (duration: 211.656266ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:13.47535Z","caller":"traceutil/trace.go:171","msg":"trace[889857916] transaction","detail":"{read_only:false; response_revision:1703; number_of_response:1; }","duration":"213.240783ms","start":"2026-07-14T17:41:13.262096Z","end":"2026-07-14T17:41:13.475337Z","steps":["trace[889857916] 'process raft request' (duration: 212.25043ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:13.475383Z","caller":"traceutil/trace.go:171","msg":"trace[1754436798] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"212.554077ms","start":"2026-07-14T17:41:13.262823Z","end":"2026-07-14T17:41:13.475377Z","steps":["trace[1754436798] 'process raft request' (duration: 211.711307ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:13.474731Z","caller":"traceutil/trace.go:171","msg":"trace[775483678] transaction","detail":"{read_only:false; response_revision:1702; number_of_response:1; }","duration":"214.003283ms","start":"2026-07-14T17:41:13.260603Z","end":"2026-07-14T17:41:13.474607Z","steps":["trace[775483678] 'process raft request' (duration: 58.067004ms)","trace[775483678] 'compare' (duration: 154.536325ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:13.673353Z","caller":"traceutil/trace.go:171","msg":"trace[1137714730] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"107.625931ms","start":"2026-07-14T17:41:13.565697Z","end":"2026-07-14T17:41:13.673323Z","steps":["trace[1137714730] 'process raft request' (duration: 19.272536ms)","trace[1137714730] 'compare' (duration: 88.220761ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:13.695429Z","caller":"traceutil/trace.go:171","msg":"trace[709683939] transaction","detail":"{read_only:false; response_revision:1709; number_of_response:1; }","duration":"126.453175ms","start":"2026-07-14T17:41:13.568951Z","end":"2026-07-14T17:41:13.695405Z","steps":["trace[709683939] 'process raft request' (duration: 125.957363ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:15.686869Z","caller":"traceutil/trace.go:171","msg":"trace[506859931] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"201.838601ms","start":"2026-07-14T17:41:15.484993Z","end":"2026-07-14T17:41:15.686831Z","steps":["trace[506859931] 'process raft request' (duration: 137.834793ms)","trace[506859931] 'compare' (duration: 63.827553ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:15.686904Z","caller":"traceutil/trace.go:171","msg":"trace[735865923] linearizableReadLoop","detail":"{readStateIndex:1813; appliedIndex:1810; }","duration":"123.224121ms","start":"2026-07-14T17:41:15.563659Z","end":"2026-07-14T17:41:15.686883Z","steps":["trace[735865923] 'read index received' (duration: 59.184122ms)","trace[735865923] 'applied index is now lower than readState.Index' (duration: 64.038739ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:15.686921Z","caller":"traceutil/trace.go:171","msg":"trace[1999626913] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"201.487062ms","start":"2026-07-14T17:41:15.48539Z","end":"2026-07-14T17:41:15.686877Z","steps":["trace[1999626913] 'process raft request' (duration: 201.371569ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:15.687002Z","caller":"traceutil/trace.go:171","msg":"trace[300456851] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"199.439112ms","start":"2026-07-14T17:41:15.487552Z","end":"2026-07-14T17:41:15.686991Z","steps":["trace[300456851] 'process raft request' (duration: 199.253198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:15.687256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.579149ms","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-07-14T17:41:15.687303Z","caller":"traceutil/trace.go:171","msg":"trace[1487951749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1738; }","duration":"123.694303ms","start":"2026-07-14T17:41:15.563599Z","end":"2026-07-14T17:41:15.687293Z","steps":["trace[1487951749] 'agreement among raft nodes before linearized reading' (duration: 123.372085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:28.284515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.71528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:41:28.284726Z","caller":"traceutil/trace.go:171","msg":"trace[782127499] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"266.625548ms","start":"2026-07-14T17:41:28.018082Z","end":"2026-07-14T17:41:28.284707Z","steps":["trace[782127499] 'process raft request' (duration: 266.562576ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:28.285059Z","caller":"traceutil/trace.go:171","msg":"trace[196817215] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"662.965045ms","start":"2026-07-14T17:41:27.622081Z","end":"2026-07-14T17:41:28.285046Z","steps":["trace[196817215] 'process raft request' (duration: 119.63883ms)","trace[196817215] 'compare' (duration: 542.607728ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:28.285121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:27.62207Z","time spent":"663.016045ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:28.285266Z","caller":"traceutil/trace.go:171","msg":"trace[2054300079] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"662.107004ms","start":"2026-07-14T17:41:27.623148Z","end":"2026-07-14T17:41:28.285255Z","steps":["trace[2054300079] 'read index received' (duration: 118.534934ms)","trace[2054300079] 'applied index is now lower than readState.Index' (duration: 543.57111ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:28.285676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.502494ms","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-07-14T17:41:28.286581Z","caller":"traceutil/trace.go:171","msg":"trace[288384852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1871; }","duration":"663.423185ms","start":"2026-07-14T17:41:27.623133Z","end":"2026-07-14T17:41:28.286557Z","steps":["trace[288384852] 'agreement among raft nodes before linearized reading' (duration: 662.284528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:28.286651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:27.623127Z","time spent":"663.491437ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-14T17:41:28.638161Z","caller":"traceutil/trace.go:171","msg":"trace[1220822466] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1949; }","duration":"352.849166ms","start":"2026-07-14T17:41:28.285286Z","end":"2026-07-14T17:41:28.638135Z","steps":["trace[1220822466] 'read index received' (duration: 280.027229ms)","trace[1220822466] 'applied index is now lower than readState.Index' (duration: 72.820997ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:28.638352Z","caller":"traceutil/trace.go:171","msg":"trace[138572672] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"594.181178ms","start":"2026-07-14T17:41:28.04413Z","end":"2026-07-14T17:41:28.638311Z","steps":["trace[138572672] 'process raft request' (duration: 521.196227ms)","trace[138572672] 'compare' (duration: 72.51102ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:28.638578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:28.044114Z","time spent":"594.363902ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:28.638649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.62245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-07-14T17:41:28.638699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.792397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-14T17:41:28.63881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.648971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T17:41:28.638886Z","caller":"traceutil/trace.go:171","msg":"trace[830672942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1872; }","duration":"870.021182ms","start":"2026-07-14T17:41:27.76884Z","end":"2026-07-14T17:41:28.638861Z","steps":["trace[830672942] 'agreement among raft nodes before linearized reading' (duration: 869.583822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:28.638943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:27.768829Z","time spent":"870.101314ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T17:41:28.638948Z","caller":"traceutil/trace.go:171","msg":"trace[899967226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1872; }","duration":"902.804515ms","start":"2026-07-14T17:41:27.736103Z","end":"2026-07-14T17:41:28.638907Z","steps":["trace[899967226] 'agreement among raft nodes before linearized reading' (duration: 902.578329ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:28.638731Z","caller":"traceutil/trace.go:171","msg":"trace[555322366] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1872; }","duration":"679.750613ms","start":"2026-07-14T17:41:27.958965Z","end":"2026-07-14T17:41:28.638716Z","steps":["trace[555322366] 'agreement among raft nodes before linearized reading' (duration: 679.479527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:28.639029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:27.736081Z","time spent":"902.935667ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:41:28.638599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.635811ms","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-07-14T17:41:28.63905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:27.95895Z","time spent":"680.08065ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T17:41:28.639082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.106408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-14T17:41:28.639119Z","caller":"traceutil/trace.go:171","msg":"trace[1441944554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1872; }","duration":"832.185183ms","start":"2026-07-14T17:41:27.806909Z","end":"2026-07-14T17:41:28.639094Z","steps":["trace[1441944554] 'agreement among raft nodes before linearized reading' (duration: 831.249281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:28.639159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:27.806899Z","time spent":"832.249065ms","remote":"127.0.0.1:58460","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":"info","ts":"2026-07-14T17:41:28.639174Z","caller":"traceutil/trace.go:171","msg":"trace[355710803] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1872; }","duration":"896.224361ms","start":"2026-07-14T17:41:27.742933Z","end":"2026-07-14T17:41:28.639158Z","steps":["trace[355710803] 'agreement among raft nodes before linearized reading' (duration: 895.31961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:28.639218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.644218ms","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-07-14T17:41:28.639296Z","caller":"traceutil/trace.go:171","msg":"trace[1371824323] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1872; }","duration":"886.743941ms","start":"2026-07-14T17:41:27.752534Z","end":"2026-07-14T17:41:28.639278Z","steps":["trace[1371824323] 'agreement among raft nodes before linearized reading' (duration: 885.658516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:28.639212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:27.742917Z","time spent":"896.285312ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-14T17:41:28.63935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:27.752525Z","time spent":"886.812783ms","remote":"127.0.0.1:58460","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-07-14T17:41:28.801561Z","caller":"traceutil/trace.go:171","msg":"trace[1595960702] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"163.304734ms","start":"2026-07-14T17:41:28.63823Z","end":"2026-07-14T17:41:28.801535Z","steps":["trace[1595960702] 'read index received' (duration: 154.901299ms)","trace[1595960702] 'applied index is now lower than readState.Index' (duration: 8.402315ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:28.801582Z","caller":"traceutil/trace.go:171","msg":"trace[1424185599] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"510.56476ms","start":"2026-07-14T17:41:28.290985Z","end":"2026-07-14T17:41:28.80155Z","steps":["trace[1424185599] 'process raft request' (duration: 502.166516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:28.801718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.30605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:28.801776Z","caller":"traceutil/trace.go:171","msg":"trace[1221629596] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1873; }","duration":"471.411413ms","start":"2026-07-14T17:41:28.330346Z","end":"2026-07-14T17:41:28.801757Z","steps":["trace[1221629596] 'agreement among raft nodes before linearized reading' (duration: 471.30585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:28.801768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:28.290968Z","time spent":"510.702983ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:28.801852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:28.330332Z","time spent":"471.486124ms","remote":"127.0.0.1:40394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:41:28.801872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.522636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-07-14T17:41:28.801816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.476946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93f31800-adfc-40ce-a1dd-3e0d365efac4\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-07-14T17:41:28.801951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.508477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:28.801966Z","caller":"traceutil/trace.go:171","msg":"trace[1110240231] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-93f31800-adfc-40ce-a1dd-3e0d365efac4; range_end:; response_count:1; response_revision:1873; }","duration":"418.65912ms","start":"2026-07-14T17:41:28.383289Z","end":"2026-07-14T17:41:28.801948Z","steps":["trace[1110240231] 'agreement among raft nodes before linearized reading' (duration: 418.328223ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:28.801969Z","caller":"traceutil/trace.go:171","msg":"trace[1427977519] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1873; }","duration":"181.659409ms","start":"2026-07-14T17:41:28.620283Z","end":"2026-07-14T17:41:28.801942Z","steps":["trace[1427977519] 'agreement among raft nodes before linearized reading' (duration: 181.392683ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:28.801995Z","caller":"traceutil/trace.go:171","msg":"trace[663688811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1873; }","duration":"456.588709ms","start":"2026-07-14T17:41:28.345393Z","end":"2026-07-14T17:41:28.801982Z","steps":["trace[663688811] 'agreement among raft nodes before linearized reading' (duration: 456.512647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:28.802017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:28.383278Z","time spent":"418.725501ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93f31800-adfc-40ce-a1dd-3e0d365efac4\" "} {"level":"warn","ts":"2026-07-14T17:41:28.802033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:28.345371Z","time spent":"456.652551ms","remote":"127.0.0.1:58278","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-07-14T17:41:29.021886Z","caller":"traceutil/trace.go:171","msg":"trace[385446462] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1951; }","duration":"218.39891ms","start":"2026-07-14T17:41:28.803463Z","end":"2026-07-14T17:41:29.021861Z","steps":["trace[385446462] 'read index received' (duration: 218.148744ms)","trace[385446462] 'applied index is now lower than readState.Index' (duration: 248.986µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:29.022036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.549163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:29.022083Z","caller":"traceutil/trace.go:171","msg":"trace[2111514400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1879; }","duration":"218.647985ms","start":"2026-07-14T17:41:28.803419Z","end":"2026-07-14T17:41:29.022067Z","steps":["trace[2111514400] 'agreement among raft nodes before linearized reading' (duration: 218.538513ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:29.02221Z","caller":"traceutil/trace.go:171","msg":"trace[1406057314] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"377.402883ms","start":"2026-07-14T17:41:28.644779Z","end":"2026-07-14T17:41:29.022182Z","steps":["trace[1406057314] 'process raft request' (duration: 377.019874ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:29.02223Z","caller":"traceutil/trace.go:171","msg":"trace[523216736] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"378.208322ms","start":"2026-07-14T17:41:28.643979Z","end":"2026-07-14T17:41:29.022187Z","steps":["trace[523216736] 'process raft request' (duration: 377.752202ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:29.022248Z","caller":"traceutil/trace.go:171","msg":"trace[333458800] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"237.43846ms","start":"2026-07-14T17:41:28.784782Z","end":"2026-07-14T17:41:29.02222Z","steps":["trace[333458800] 'process raft request' (duration: 237.046131ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:29.022304Z","caller":"traceutil/trace.go:171","msg":"trace[1283438574] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"377.523706ms","start":"2026-07-14T17:41:28.644757Z","end":"2026-07-14T17:41:29.022281Z","steps":["trace[1283438574] 'process raft request' (duration: 377.010944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:29.022368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:28.644769Z","time spent":"377.521915ms","remote":"127.0.0.1:58460","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-07-14T17:41:29.022523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:28.644748Z","time spent":"377.598148ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:29.022593Z","caller":"traceutil/trace.go:171","msg":"trace[2128413688] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"378.679503ms","start":"2026-07-14T17:41:28.643891Z","end":"2026-07-14T17:41:29.022571Z","steps":["trace[2128413688] 'process raft request' (duration: 377.656629ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:29.022684Z","caller":"traceutil/trace.go:171","msg":"trace[854751672] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"378.749514ms","start":"2026-07-14T17:41:28.643918Z","end":"2026-07-14T17:41:29.022667Z","steps":["trace[854751672] 'process raft request' (duration: 377.769502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:29.022673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:28.643972Z","time spent":"378.343015ms","remote":"127.0.0.1:58460","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-07-14T17:41:29.022796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:28.6439Z","time spent":"378.820216ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:29.0233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:28.643873Z","time spent":"378.758584ms","remote":"127.0.0.1:58460","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-07-14T17:41:37.150001Z","caller":"traceutil/trace.go:171","msg":"trace[1083016475] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"148.52073ms","start":"2026-07-14T17:41:37.001449Z","end":"2026-07-14T17:41:37.14997Z","steps":["trace[1083016475] 'process raft request' (duration: 143.874455ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:43.805644Z","caller":"traceutil/trace.go:171","msg":"trace[1799926279] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"126.316045ms","start":"2026-07-14T17:41:43.67929Z","end":"2026-07-14T17:41:43.805606Z","steps":["trace[1799926279] 'process raft request' (duration: 126.067629ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:44.984258Z","caller":"traceutil/trace.go:171","msg":"trace[115698926] linearizableReadLoop","detail":"{readStateIndex:2090; appliedIndex:2089; }","duration":"281.565059ms","start":"2026-07-14T17:41:44.702669Z","end":"2026-07-14T17:41:44.984234Z","steps":["trace[115698926] 'read index received' (duration: 281.360344ms)","trace[115698926] 'applied index is now lower than readState.Index' (duration: 203.565µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:44.984607Z","caller":"traceutil/trace.go:171","msg":"trace[1893736653] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"285.997566ms","start":"2026-07-14T17:41:44.698593Z","end":"2026-07-14T17:41:44.984591Z","steps":["trace[1893736653] 'process raft request' (duration: 285.513506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:44.984908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.223943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:41:44.984972Z","caller":"traceutil/trace.go:171","msg":"trace[1750049224] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2008; }","duration":"282.325526ms","start":"2026-07-14T17:41:44.702626Z","end":"2026-07-14T17:41:44.984951Z","steps":["trace[1750049224] 'agreement among raft nodes before linearized reading' (duration: 282.221153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:44.985168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.994232ms","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-07-14T17:41:44.985237Z","caller":"traceutil/trace.go:171","msg":"trace[198580372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2008; }","duration":"104.107185ms","start":"2026-07-14T17:41:44.881116Z","end":"2026-07-14T17:41:44.985223Z","steps":["trace[198580372] 'agreement among raft nodes before linearized reading' (duration: 103.91435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:05.357786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.022885Z","time spent":"334.893055ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-14T17:42:06.068917Z","caller":"traceutil/trace.go:171","msg":"trace[1945051505] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"1.008229246s","start":"2026-07-14T17:42:05.06067Z","end":"2026-07-14T17:42:06.068899Z","steps":["trace[1945051505] 'process raft request' (duration: 1.008075413s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.069027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.060642Z","time spent":"1.008330418s","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:42:06.069126Z","caller":"traceutil/trace.go:171","msg":"trace[2059951013] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2211; }","duration":"962.335605ms","start":"2026-07-14T17:42:05.106765Z","end":"2026-07-14T17:42:06.069101Z","steps":["trace[2059951013] 'read index received' (duration: 250.917558ms)","trace[2059951013] 'applied index is now lower than readState.Index' (duration: 711.416447ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:42:06.069312Z","caller":"traceutil/trace.go:171","msg":"trace[1081354841] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"715.023783ms","start":"2026-07-14T17:42:05.354178Z","end":"2026-07-14T17:42:06.069201Z","steps":["trace[1081354841] 'process raft request' (duration: 714.685056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.069396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.625022ms","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-07-14T17:42:06.069616Z","caller":"traceutil/trace.go:171","msg":"trace[1970900660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2126; }","duration":"962.862697ms","start":"2026-07-14T17:42:05.106691Z","end":"2026-07-14T17:42:06.069554Z","steps":["trace[1970900660] 'agreement among raft nodes before linearized reading' (duration: 962.578081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.069691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.106665Z","time spent":"963.011519ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T17:42:06.069788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.354142Z","time spent":"715.33738ms","remote":"127.0.0.1:58460","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-07-14T17:42:06.340147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.082551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:42:06.340586Z","caller":"traceutil/trace.go:171","msg":"trace[102286749] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"379.656526ms","start":"2026-07-14T17:42:05.960906Z","end":"2026-07-14T17:42:06.340563Z","steps":["trace[102286749] 'process raft request' (duration: 379.324309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.340655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.960882Z","time spent":"379.743958ms","remote":"127.0.0.1:58460","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-07-14T17:42:06.340686Z","caller":"traceutil/trace.go:171","msg":"trace[1861646961] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"981.424696ms","start":"2026-07-14T17:42:05.359235Z","end":"2026-07-14T17:42:06.34066Z","steps":["trace[1861646961] 'process raft request' (duration: 837.750823ms)","trace[1861646961] 'compare' (duration: 142.745064ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:42:06.340825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.359224Z","time spent":"981.522749ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:42:06.340839Z","caller":"traceutil/trace.go:171","msg":"trace[912915164] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2214; }","duration":"271.542832ms","start":"2026-07-14T17:42:06.069249Z","end":"2026-07-14T17:42:06.340791Z","steps":["trace[912915164] 'read index received' (duration: 127.755927ms)","trace[912915164] 'applied index is now lower than readState.Index' (duration: 143.781805ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:42:06.340971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.156352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T17:42:06.341001Z","caller":"traceutil/trace.go:171","msg":"trace[528055738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2129; }","duration":"938.207612ms","start":"2026-07-14T17:42:05.402786Z","end":"2026-07-14T17:42:06.340993Z","steps":["trace[528055738] 'agreement among raft nodes before linearized reading' (duration: 938.10294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.341024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.402775Z","time spent":"938.244303ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:42:06.341088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.905264ms","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-07-14T17:42:06.3412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.988874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-14T17:42:06.341228Z","caller":"traceutil/trace.go:171","msg":"trace[133469341] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2129; }","duration":"908.035984ms","start":"2026-07-14T17:42:05.433183Z","end":"2026-07-14T17:42:06.341219Z","steps":["trace[133469341] 'agreement among raft nodes before linearized reading' (duration: 907.973283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.341218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140171575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-07-14T17:42:06.341237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.755671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T17:42:06.341248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.433171Z","time spent":"908.071255ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-14T17:42:06.341264Z","caller":"traceutil/trace.go:171","msg":"trace[417887847] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2129; }","duration":"1.140262987s","start":"2026-07-14T17:42:05.200988Z","end":"2026-07-14T17:42:06.341251Z","steps":["trace[417887847] 'agreement among raft nodes before linearized reading' (duration: 1.140102773s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:06.34128Z","caller":"traceutil/trace.go:171","msg":"trace[1847576763] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2129; }","duration":"167.840293ms","start":"2026-07-14T17:42:06.173426Z","end":"2026-07-14T17:42:06.341266Z","steps":["trace[1847576763] 'agreement among raft nodes before linearized reading' (duration: 167.768932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.34128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.34708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"warn","ts":"2026-07-14T17:42:06.341308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.200965Z","time spent":"1.140336299s","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-14T17:42:06.341152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.240818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c238dc33830f67\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-14T17:42:06.341331Z","caller":"traceutil/trace.go:171","msg":"trace[1383903116] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2129; }","duration":"406.422092ms","start":"2026-07-14T17:42:05.934893Z","end":"2026-07-14T17:42:06.341315Z","steps":["trace[1383903116] 'agreement among raft nodes before linearized reading' (duration: 406.2989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.341315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.061321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-14T17:42:06.341463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.446213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:42:06.341523Z","caller":"traceutil/trace.go:171","msg":"trace[491344615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2129; }","duration":"995.538385ms","start":"2026-07-14T17:42:05.34597Z","end":"2026-07-14T17:42:06.341508Z","steps":["trace[491344615] 'agreement among raft nodes before linearized reading' (duration: 995.461604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.34157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.345948Z","time spent":"995.611336ms","remote":"127.0.0.1:58278","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-07-14T17:42:06.341557Z","caller":"traceutil/trace.go:171","msg":"trace[102222489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2129; }","duration":"913.363387ms","start":"2026-07-14T17:42:05.428132Z","end":"2026-07-14T17:42:06.341495Z","steps":["trace[102222489] 'agreement among raft nodes before linearized reading' (duration: 912.819075ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:06.34135Z","caller":"traceutil/trace.go:171","msg":"trace[84720175] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c238dc33830f67; range_end:; response_count:1; response_revision:2129; }","duration":"414.505294ms","start":"2026-07-14T17:42:05.926837Z","end":"2026-07-14T17:42:06.341342Z","steps":["trace[84720175] 'agreement among raft nodes before linearized reading' (duration: 414.167847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.341625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.926801Z","time spent":"414.81495ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c238dc33830f67\" "} {"level":"warn","ts":"2026-07-14T17:42:06.341396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.934882Z","time spent":"406.500774ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12409,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-14T17:42:06.341683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.428109Z","time spent":"913.502709ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T17:42:06.341397Z","caller":"traceutil/trace.go:171","msg":"trace[18818485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2129; }","duration":"910.055647ms","start":"2026-07-14T17:42:05.431142Z","end":"2026-07-14T17:42:06.341197Z","steps":["trace[18818485] 'agreement among raft nodes before linearized reading' (duration: 909.659638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.341849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.43113Z","time spent":"910.701791ms","remote":"127.0.0.1:58460","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-07-14T17:42:06.341151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.629206ms","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-07-14T17:42:06.342007Z","caller":"traceutil/trace.go:171","msg":"trace[541406501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2129; }","duration":"943.549746ms","start":"2026-07-14T17:42:05.398449Z","end":"2026-07-14T17:42:06.341999Z","steps":["trace[541406501] 'agreement among raft nodes before linearized reading' (duration: 942.520444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.342034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:05.398426Z","time spent":"943.599646ms","remote":"127.0.0.1:58460","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":"info","ts":"2026-07-14T17:42:06.341479Z","caller":"traceutil/trace.go:171","msg":"trace[1980986283] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"266.053376ms","start":"2026-07-14T17:42:06.075417Z","end":"2026-07-14T17:42:06.34147Z","steps":["trace[1980986283] 'process raft request' (duration: 264.866751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.925698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.33819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:42:06.926328Z","caller":"traceutil/trace.go:171","msg":"trace[1389487369] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"579.322719ms","start":"2026-07-14T17:42:06.346965Z","end":"2026-07-14T17:42:06.926288Z","steps":["trace[1389487369] 'process raft request' (duration: 422.312004ms)","trace[1389487369] 'compare' (duration: 156.245018ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:42:06.926624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:06.346954Z","time spent":"579.496102ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:42:06.926733Z","caller":"traceutil/trace.go:171","msg":"trace[1632410649] linearizableReadLoop","detail":"{readStateIndex:2219; appliedIndex:2217; }","duration":"578.038621ms","start":"2026-07-14T17:42:06.348613Z","end":"2026-07-14T17:42:06.926652Z","steps":["trace[1632410649] 'read index received' (duration: 420.68192ms)","trace[1632410649] 'applied index is now lower than readState.Index' (duration: 157.355371ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:42:06.926757Z","caller":"traceutil/trace.go:171","msg":"trace[1192119907] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"577.792466ms","start":"2026-07-14T17:42:06.348943Z","end":"2026-07-14T17:42:06.926736Z","steps":["trace[1192119907] 'process raft request' (duration: 577.330026ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:06.926901Z","caller":"traceutil/trace.go:171","msg":"trace[683772619] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"577.830716ms","start":"2026-07-14T17:42:06.349056Z","end":"2026-07-14T17:42:06.926887Z","steps":["trace[683772619] 'process raft request' (duration: 577.257534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.927962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:06.349048Z","time spent":"578.844618ms","remote":"127.0.0.1:58460","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-07-14T17:42:06.926913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:06.348916Z","time spent":"577.918958ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:42:06.927029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.412709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-14T17:42:06.928268Z","caller":"traceutil/trace.go:171","msg":"trace[2129861823] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2135; }","duration":"579.671176ms","start":"2026-07-14T17:42:06.34858Z","end":"2026-07-14T17:42:06.928252Z","steps":["trace[2129861823] 'agreement among raft nodes before linearized reading' (duration: 578.370599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.928412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:06.348561Z","time spent":"579.800098ms","remote":"127.0.0.1:58476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-07-14T17:42:06.927075Z","caller":"traceutil/trace.go:171","msg":"trace[1422705870] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"577.624682ms","start":"2026-07-14T17:42:06.349438Z","end":"2026-07-14T17:42:06.927063Z","steps":["trace[1422705870] 'process raft request' (duration: 576.920657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.92854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:06.349428Z","time spent":"579.050603ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:42:06.927115Z","caller":"traceutil/trace.go:171","msg":"trace[509124514] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"577.609412ms","start":"2026-07-14T17:42:06.349492Z","end":"2026-07-14T17:42:06.927101Z","steps":["trace[509124514] 'process raft request' (duration: 577.099931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.928701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:06.349487Z","time spent":"579.179425ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:42:06.927191Z","caller":"traceutil/trace.go:171","msg":"trace[1861410545] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"580.145565ms","start":"2026-07-14T17:42:06.347031Z","end":"2026-07-14T17:42:06.927177Z","steps":["trace[1861410545] 'process raft request' (duration: 579.164675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.928828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:06.347007Z","time spent":"581.77978ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:42:06.927285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.693571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:42:06.927335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.679622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-14T17:42:06.92953Z","caller":"traceutil/trace.go:171","msg":"trace[728670590] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2135; }","duration":"343.91624ms","start":"2026-07-14T17:42:06.5856Z","end":"2026-07-14T17:42:06.929516Z","steps":["trace[728670590] 'agreement among raft nodes before linearized reading' (duration: 341.608331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.927373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.881859ms","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-07-14T17:42:06.929641Z","caller":"traceutil/trace.go:171","msg":"trace[267556094] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2135; }","duration":"446.145967ms","start":"2026-07-14T17:42:06.483478Z","end":"2026-07-14T17:42:06.929623Z","steps":["trace[267556094] 'agreement among raft nodes before linearized reading' (duration: 443.866109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.929705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:06.483407Z","time spent":"446.27623ms","remote":"127.0.0.1:58484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:42:06.929764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:06.585574Z","time spent":"344.037362ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-07-14T17:42:06.927427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.72142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:42:06.929899Z","caller":"traceutil/trace.go:171","msg":"trace[615467784] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2135; }","duration":"576.199142ms","start":"2026-07-14T17:42:06.353688Z","end":"2026-07-14T17:42:06.929887Z","steps":["trace[615467784] 'agreement among raft nodes before linearized reading' (duration: 573.711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.929945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:06.353684Z","time spent":"576.249423ms","remote":"127.0.0.1:58634","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-07-14T17:42:06.927455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.027889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:42:06.930149Z","caller":"traceutil/trace.go:171","msg":"trace[1426140381] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2135; }","duration":"190.755837ms","start":"2026-07-14T17:42:06.739374Z","end":"2026-07-14T17:42:06.93013Z","steps":["trace[1426140381] 'agreement among raft nodes before linearized reading' (duration: 188.017129ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:06.930813Z","caller":"traceutil/trace.go:171","msg":"trace[864140401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2135; }","duration":"576.422477ms","start":"2026-07-14T17:42:06.352556Z","end":"2026-07-14T17:42:06.928978Z","steps":["trace[864140401] 'agreement among raft nodes before linearized reading' (duration: 574.626559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:06.930929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:42:06.352529Z","time spent":"578.347737ms","remote":"127.0.0.1:58278","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-07-14T17:42:26.635164Z","caller":"traceutil/trace.go:171","msg":"trace[1068135230] linearizableReadLoop","detail":"{readStateIndex:2327; appliedIndex:2326; }","duration":"108.137239ms","start":"2026-07-14T17:42:26.527006Z","end":"2026-07-14T17:42:26.635143Z","steps":["trace[1068135230] 'read index received' (duration: 107.923975ms)","trace[1068135230] 'applied index is now lower than readState.Index' (duration: 212.304µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:42:26.635433Z","caller":"traceutil/trace.go:171","msg":"trace[549268763] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"118.720484ms","start":"2026-07-14T17:42:26.516699Z","end":"2026-07-14T17:42:26.63542Z","steps":["trace[549268763] 'process raft request' (duration: 118.324166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:26.635566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.406753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:42:26.635678Z","caller":"traceutil/trace.go:171","msg":"trace[576542431] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2235; }","duration":"102.538165ms","start":"2026-07-14T17:42:26.533118Z","end":"2026-07-14T17:42:26.635657Z","steps":["trace[576542431] 'agreement among raft nodes before linearized reading' (duration: 102.302941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:26.635728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.69045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-14T17:42:26.635814Z","caller":"traceutil/trace.go:171","msg":"trace[59032869] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2235; }","duration":"108.825263ms","start":"2026-07-14T17:42:26.52697Z","end":"2026-07-14T17:42:26.635795Z","steps":["trace[59032869] 'agreement among raft nodes before linearized reading' (duration: 108.546448ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:30.921661Z","caller":"traceutil/trace.go:171","msg":"trace[140287413] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"115.274695ms","start":"2026-07-14T17:42:30.806362Z","end":"2026-07-14T17:42:30.921637Z","steps":["trace[140287413] 'process raft request' (duration: 115.143563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:13.121849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.778576ms","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-07-14T17:43:13.12195Z","caller":"traceutil/trace.go:171","msg":"trace[50212080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2589; }","duration":"236.926249ms","start":"2026-07-14T17:43:12.885005Z","end":"2026-07-14T17:43:13.121931Z","steps":["trace[50212080] 'range keys from in-memory index tree' (duration: 236.548192ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:48.601219Z","caller":"traceutil/trace.go:171","msg":"trace[1144203089] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"158.544798ms","start":"2026-07-14T17:43:48.44265Z","end":"2026-07-14T17:43:48.601195Z","steps":["trace[1144203089] 'process raft request' (duration: 158.384945ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:48.612921Z","caller":"traceutil/trace.go:171","msg":"trace[465080419] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"168.712749ms","start":"2026-07-14T17:43:48.444158Z","end":"2026-07-14T17:43:48.612871Z","steps":["trace[465080419] 'process raft request' (duration: 168.531306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:49.128707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.938051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:43:49.128832Z","caller":"traceutil/trace.go:171","msg":"trace[128437731] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2786; }","duration":"229.117604ms","start":"2026-07-14T17:43:48.899676Z","end":"2026-07-14T17:43:49.128794Z","steps":["trace[128437731] 'count revisions from in-memory index tree' (duration: 228.83151ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:49.40587Z","caller":"traceutil/trace.go:171","msg":"trace[1476553737] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"425.98266ms","start":"2026-07-14T17:43:48.979842Z","end":"2026-07-14T17:43:49.405825Z","steps":["trace[1476553737] 'process raft request' (duration: 425.802527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:49.406553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:43:48.979822Z","time spent":"426.140862ms","remote":"127.0.0.1:58460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:44:42.023183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.222264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:44:42.023401Z","caller":"traceutil/trace.go:171","msg":"trace[647448253] transaction","detail":"{read_only:false; response_revision:3067; number_of_response:1; }","duration":"232.467135ms","start":"2026-07-14T17:44:41.790895Z","end":"2026-07-14T17:44:42.023362Z","steps":["trace[647448253] 'process raft request' (duration: 127.657101ms)","trace[647448253] 'compare' (duration: 103.992ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:44:42.023489Z","caller":"traceutil/trace.go:171","msg":"trace[458178561] transaction","detail":"{read_only:false; response_revision:3069; number_of_response:1; }","duration":"231.303425ms","start":"2026-07-14T17:44:41.79217Z","end":"2026-07-14T17:44:42.023474Z","steps":["trace[458178561] 'process raft request' (duration: 231.262854ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:42.023684Z","caller":"traceutil/trace.go:171","msg":"trace[1415124826] transaction","detail":"{read_only:false; response_revision:3068; number_of_response:1; }","duration":"231.800883ms","start":"2026-07-14T17:44:41.791873Z","end":"2026-07-14T17:44:42.023674Z","steps":["trace[1415124826] 'process raft request' (duration: 231.492568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:31.756036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.188461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:47:31.7561Z","caller":"traceutil/trace.go:171","msg":"trace[1211395199] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4122; }","duration":"110.286594ms","start":"2026-07-14T17:47:31.645802Z","end":"2026-07-14T17:47:31.756088Z","steps":["trace[1211395199] 'range keys from in-memory index tree' (duration: 110.123969ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:36.189567Z","caller":"traceutil/trace.go:171","msg":"trace[2020630856] transaction","detail":"{read_only:false; response_revision:4148; number_of_response:1; }","duration":"101.154114ms","start":"2026-07-14T17:47:36.088386Z","end":"2026-07-14T17:47:36.18954Z","steps":["trace[2020630856] 'process raft request' (duration: 100.651427ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:51.842595Z","caller":"traceutil/trace.go:171","msg":"trace[984471699] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"203.419312ms","start":"2026-07-14T17:47:51.639149Z","end":"2026-07-14T17:47:51.842569Z","steps":["trace[984471699] 'process raft request' (duration: 203.191184ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:51.861974Z","caller":"traceutil/trace.go:171","msg":"trace[358720358] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"177.768155ms","start":"2026-07-14T17:47:51.684181Z","end":"2026-07-14T17:47:51.861949Z","steps":["trace[358720358] 'process raft request' (duration: 177.61829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:53.027769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.288309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60a302bf-79c5-4b60-90f1-db479dc5b1ac\" ","response":"range_response_count:1 size:4074"} {"level":"info","ts":"2026-07-14T17:47:53.027869Z","caller":"traceutil/trace.go:171","msg":"trace[222243223] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60a302bf-79c5-4b60-90f1-db479dc5b1ac; range_end:; response_count:1; response_revision:4695; }","duration":"124.428593ms","start":"2026-07-14T17:47:52.903421Z","end":"2026-07-14T17:47:53.027849Z","steps":["trace[222243223] 'range keys from in-memory index tree' (duration: 124.056342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:53.027895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.503094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T17:47:53.028031Z","caller":"traceutil/trace.go:171","msg":"trace[1779245643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4695; }","duration":"131.664289ms","start":"2026-07-14T17:47:52.896336Z","end":"2026-07-14T17:47:53.028Z","steps":["trace[1779245643] 'range keys from in-memory index tree' (duration: 131.291208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:53.028233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.80105ms","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-07-14T17:47:53.028286Z","caller":"traceutil/trace.go:171","msg":"trace[1228210298] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4695; }","duration":"168.876572ms","start":"2026-07-14T17:47:52.859393Z","end":"2026-07-14T17:47:53.028269Z","steps":["trace[1228210298] 'range keys from in-memory index tree' (duration: 168.646945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:56.039248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.081342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:47:56.039422Z","caller":"traceutil/trace.go:171","msg":"trace[1857800995] linearizableReadLoop","detail":"{readStateIndex:4955; appliedIndex:4953; }","duration":"180.778569ms","start":"2026-07-14T17:47:55.858632Z","end":"2026-07-14T17:47:56.039411Z","steps":["trace[1857800995] 'read index received' (duration: 39.549182ms)","trace[1857800995] 'applied index is now lower than readState.Index' (duration: 141.228697ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:47:56.039485Z","caller":"traceutil/trace.go:171","msg":"trace[1270257276] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"184.719507ms","start":"2026-07-14T17:47:55.854758Z","end":"2026-07-14T17:47:56.039478Z","steps":["trace[1270257276] 'process raft request' (duration: 43.358346ms)","trace[1270257276] 'compare' (duration: 140.973608ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:47:56.039717Z","caller":"traceutil/trace.go:171","msg":"trace[146208216] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"184.897582ms","start":"2026-07-14T17:47:55.85481Z","end":"2026-07-14T17:47:56.039707Z","steps":["trace[146208216] 'process raft request' (duration: 184.52311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:56.039983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.471064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-07-14T17:47:56.040006Z","caller":"traceutil/trace.go:171","msg":"trace[1806714370] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4768; }","duration":"153.522976ms","start":"2026-07-14T17:47:55.886476Z","end":"2026-07-14T17:47:56.039999Z","steps":["trace[1806714370] 'agreement among raft nodes before linearized reading' (duration: 153.149594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:56.041188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.529326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11454"} {"level":"info","ts":"2026-07-14T17:47:56.041253Z","caller":"traceutil/trace.go:171","msg":"trace[1587578071] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4768; }","duration":"182.63666ms","start":"2026-07-14T17:47:55.858599Z","end":"2026-07-14T17:47:56.041236Z","steps":["trace[1587578071] 'agreement among raft nodes before linearized reading' (duration: 181.013137ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:56.039768Z","caller":"traceutil/trace.go:171","msg":"trace[619347972] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"166.787216ms","start":"2026-07-14T17:47:55.872967Z","end":"2026-07-14T17:47:56.039754Z","steps":["trace[619347972] 'process raft request' (duration: 166.412894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:56.968775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.91837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:47:56.968876Z","caller":"traceutil/trace.go:171","msg":"trace[1028946522] linearizableReadLoop","detail":"{readStateIndex:4966; appliedIndex:4964; }","duration":"264.941814ms","start":"2026-07-14T17:47:56.703918Z","end":"2026-07-14T17:47:56.96886Z","steps":["trace[1028946522] 'read index received' (duration: 25.33099ms)","trace[1028946522] 'applied index is now lower than readState.Index' (duration: 239.609084ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:47:56.96911Z","caller":"traceutil/trace.go:171","msg":"trace[363698283] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"271.555809ms","start":"2026-07-14T17:47:56.697541Z","end":"2026-07-14T17:47:56.969096Z","steps":["trace[363698283] 'process raft request' (duration: 126.188924ms)","trace[363698283] 'compare' (duration: 144.802487ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:47:56.969285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.349327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:47:56.969351Z","caller":"traceutil/trace.go:171","msg":"trace[1772824988] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:4778; }","duration":"265.448861ms","start":"2026-07-14T17:47:56.703886Z","end":"2026-07-14T17:47:56.969335Z","steps":["trace[1772824988] 'agreement among raft nodes before linearized reading' (duration: 265.282885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:56.993001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.774078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T17:47:56.993098Z","caller":"traceutil/trace.go:171","msg":"trace[121117414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4779; }","duration":"187.922102ms","start":"2026-07-14T17:47:56.805151Z","end":"2026-07-14T17:47:56.993073Z","steps":["trace[121117414] 'agreement among raft nodes before linearized reading' (duration: 187.625902ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:56.993114Z","caller":"traceutil/trace.go:171","msg":"trace[1468846780] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"256.512982ms","start":"2026-07-14T17:47:56.736544Z","end":"2026-07-14T17:47:56.993057Z","steps":["trace[1468846780] 'process raft request' (duration: 256.095158ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:57.647525Z","caller":"traceutil/trace.go:171","msg":"trace[517128028] linearizableReadLoop","detail":"{readStateIndex:4976; appliedIndex:4974; }","duration":"114.880951ms","start":"2026-07-14T17:47:57.532623Z","end":"2026-07-14T17:47:57.647503Z","steps":["trace[517128028] 'read index received' (duration: 81.308797ms)","trace[517128028] 'applied index is now lower than readState.Index' (duration: 33.571274ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:47:57.647625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.986214ms","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-07-14T17:47:57.647646Z","caller":"traceutil/trace.go:171","msg":"trace[692608124] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4788; }","duration":"115.043217ms","start":"2026-07-14T17:47:57.532597Z","end":"2026-07-14T17:47:57.64764Z","steps":["trace[692608124] 'agreement among raft nodes before linearized reading' (duration: 114.977015ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:57.647835Z","caller":"traceutil/trace.go:171","msg":"trace[1985795657] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"124.472591ms","start":"2026-07-14T17:47:57.523354Z","end":"2026-07-14T17:47:57.647827Z","steps":["trace[1985795657] 'process raft request' (duration: 124.041657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:59.77686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.296759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b6a8d761-b88f-4d88-9472-d23c83df2aaa\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-14T17:47:59.776951Z","caller":"traceutil/trace.go:171","msg":"trace[1597360139] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b6a8d761-b88f-4d88-9472-d23c83df2aaa; range_end:; response_count:1; response_revision:4856; }","duration":"223.438403ms","start":"2026-07-14T17:47:59.553491Z","end":"2026-07-14T17:47:59.77693Z","steps":["trace[1597360139] 'range keys from in-memory index tree' (duration: 223.066461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:59.777128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.937403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:47:59.777211Z","caller":"traceutil/trace.go:171","msg":"trace[927866590] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4856; }","duration":"180.060657ms","start":"2026-07-14T17:47:59.597132Z","end":"2026-07-14T17:47:59.777193Z","steps":["trace[927866590] 'count revisions from in-memory index tree' (duration: 179.874821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:59.777589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.262393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b6a8d761-b88f-4d88-9472-d23c83df2aaa\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-14T17:47:59.777649Z","caller":"traceutil/trace.go:171","msg":"trace[212134090] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b6a8d761-b88f-4d88-9472-d23c83df2aaa; range_end:; response_count:1; response_revision:4856; }","duration":"244.345846ms","start":"2026-07-14T17:47:59.533289Z","end":"2026-07-14T17:47:59.777635Z","steps":["trace[212134090] 'range keys from in-memory index tree' (duration: 244.14766ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:00.206976Z","caller":"traceutil/trace.go:171","msg":"trace[99385428] linearizableReadLoop","detail":"{readStateIndex:5046; appliedIndex:5045; }","duration":"338.709853ms","start":"2026-07-14T17:47:59.868239Z","end":"2026-07-14T17:48:00.206949Z","steps":["trace[99385428] 'read index received' (duration: 338.672331ms)","trace[99385428] 'applied index is now lower than readState.Index' (duration: 36.282µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:48:00.207107Z","caller":"traceutil/trace.go:171","msg":"trace[137127904] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"421.539919ms","start":"2026-07-14T17:47:59.785555Z","end":"2026-07-14T17:48:00.207095Z","steps":["trace[137127904] 'process raft request' (duration: 421.23954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:00.207237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:47:59.785542Z","time spent":"421.594151ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:48:00.207351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.044663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-r9z4f-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-07-14T17:48:00.207413Z","caller":"traceutil/trace.go:171","msg":"trace[1428236256] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-r9z4f-tls; range_end:; response_count:1; response_revision:4857; }","duration":"339.196308ms","start":"2026-07-14T17:47:59.868202Z","end":"2026-07-14T17:48:00.207398Z","steps":["trace[1428236256] 'agreement among raft nodes before linearized reading' (duration: 338.93734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:00.20745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:47:59.868184Z","time spent":"339.260131ms","remote":"127.0.0.1:46030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":1990,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-r9z4f-tls\" "} {"level":"info","ts":"2026-07-14T17:48:00.226911Z","caller":"traceutil/trace.go:171","msg":"trace[958196581] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"177.170673ms","start":"2026-07-14T17:48:00.049711Z","end":"2026-07-14T17:48:00.226882Z","steps":["trace[958196581] 'process raft request' (duration: 176.916815ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:00.22698Z","caller":"traceutil/trace.go:171","msg":"trace[1790485614] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"173.166216ms","start":"2026-07-14T17:48:00.053791Z","end":"2026-07-14T17:48:00.226957Z","steps":["trace[1790485614] 'process raft request' (duration: 173.001961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:00.227134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.130891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18c2392f7db103f8\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-07-14T17:48:00.227153Z","caller":"traceutil/trace.go:171","msg":"trace[1773589022] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"149.130342ms","start":"2026-07-14T17:48:00.077999Z","end":"2026-07-14T17:48:00.227129Z","steps":["trace[1773589022] 'process raft request' (duration: 148.853513ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:00.227216Z","caller":"traceutil/trace.go:171","msg":"trace[1925436168] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18c2392f7db103f8; range_end:; response_count:1; response_revision:4860; }","duration":"346.255964ms","start":"2026-07-14T17:47:59.880939Z","end":"2026-07-14T17:48:00.227195Z","steps":["trace[1925436168] 'agreement among raft nodes before linearized reading' (duration: 346.036957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:00.22726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:47:59.880918Z","time spent":"346.333097ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":1035,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18c2392f7db103f8\" "} {"level":"warn","ts":"2026-07-14T17:48:03.720523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.033631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:48:03.720647Z","caller":"traceutil/trace.go:171","msg":"trace[568884162] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5109; }","duration":"127.886168ms","start":"2026-07-14T17:48:03.59275Z","end":"2026-07-14T17:48:03.720637Z","steps":["trace[568884162] 'read index received' (duration: 126.261556ms)","trace[568884162] 'applied index is now lower than readState.Index' (duration: 1.624082ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:48:03.720685Z","caller":"traceutil/trace.go:171","msg":"trace[1151392] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"136.98936ms","start":"2026-07-14T17:48:03.58369Z","end":"2026-07-14T17:48:03.72068Z","steps":["trace[1151392] 'compare' (duration: 131.940458ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:03.720799Z","caller":"traceutil/trace.go:171","msg":"trace[2098093552] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"128.052344ms","start":"2026-07-14T17:48:03.592701Z","end":"2026-07-14T17:48:03.720754Z","steps":["trace[2098093552] 'process raft request' (duration: 127.890179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:03.721059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.294691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-07-14T17:48:03.721173Z","caller":"traceutil/trace.go:171","msg":"trace[1549378419] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4920; }","duration":"128.410205ms","start":"2026-07-14T17:48:03.592745Z","end":"2026-07-14T17:48:03.721155Z","steps":["trace[1549378419] 'agreement among raft nodes before linearized reading' (duration: 128.064974ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:03.722695Z","caller":"traceutil/trace.go:171","msg":"trace[1608020957] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"126.263577ms","start":"2026-07-14T17:48:03.596417Z","end":"2026-07-14T17:48:03.72268Z","steps":["trace[1608020957] 'process raft request' (duration: 126.000608ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:03.722885Z","caller":"traceutil/trace.go:171","msg":"trace[1011169006] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"126.37385ms","start":"2026-07-14T17:48:03.59649Z","end":"2026-07-14T17:48:03.722863Z","steps":["trace[1011169006] 'process raft request' (duration: 125.989498ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:03.723359Z","caller":"traceutil/trace.go:171","msg":"trace[1764557804] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"126.307208ms","start":"2026-07-14T17:48:03.59704Z","end":"2026-07-14T17:48:03.723347Z","steps":["trace[1764557804] 'process raft request' (duration: 125.477221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:03.722649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.060044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:48:03.727613Z","caller":"traceutil/trace.go:171","msg":"trace[1459559792] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:4923; }","duration":"133.041903ms","start":"2026-07-14T17:48:03.594544Z","end":"2026-07-14T17:48:03.727585Z","steps":["trace[1459559792] 'agreement among raft nodes before linearized reading' (duration: 128.009383ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:11.81083Z","caller":"traceutil/trace.go:171","msg":"trace[335576526] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"120.992355ms","start":"2026-07-14T17:48:11.689576Z","end":"2026-07-14T17:48:11.810568Z","steps":["trace[335576526] 'process raft request' (duration: 60.005972ms)","trace[335576526] 'compare' (duration: 60.832028ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:48:11.827896Z","caller":"traceutil/trace.go:171","msg":"trace[2006469418] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"136.470073ms","start":"2026-07-14T17:48:11.691403Z","end":"2026-07-14T17:48:11.827873Z","steps":["trace[2006469418] 'process raft request' (duration: 136.3703ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:11.828164Z","caller":"traceutil/trace.go:171","msg":"trace[1532703434] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"136.969719ms","start":"2026-07-14T17:48:11.691182Z","end":"2026-07-14T17:48:11.828152Z","steps":["trace[1532703434] 'process raft request' (duration: 136.458822ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:11.828463Z","caller":"traceutil/trace.go:171","msg":"trace[474912724] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"136.121572ms","start":"2026-07-14T17:48:11.692306Z","end":"2026-07-14T17:48:11.828428Z","steps":["trace[474912724] 'process raft request' (duration: 135.519553ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:13.313756Z","caller":"traceutil/trace.go:171","msg":"trace[688536058] linearizableReadLoop","detail":"{readStateIndex:5193; appliedIndex:5192; }","duration":"106.167816ms","start":"2026-07-14T17:48:13.207566Z","end":"2026-07-14T17:48:13.313733Z","steps":["trace[688536058] 'read index received' (duration: 105.98214ms)","trace[688536058] 'applied index is now lower than readState.Index' (duration: 184.466µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:48:13.313825Z","caller":"traceutil/trace.go:171","msg":"trace[13236804] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"153.299437ms","start":"2026-07-14T17:48:13.160493Z","end":"2026-07-14T17:48:13.313792Z","steps":["trace[13236804] 'process raft request' (duration: 153.07532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:13.313939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.339591ms","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-07-14T17:48:13.314042Z","caller":"traceutil/trace.go:171","msg":"trace[367845606] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5000; }","duration":"106.503836ms","start":"2026-07-14T17:48:13.207512Z","end":"2026-07-14T17:48:13.314016Z","steps":["trace[367845606] 'agreement among raft nodes before linearized reading' (duration: 106.31388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:42.113637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.443393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-credential-setup-wdvjd\" ","response":"range_response_count:1 size:7557"} {"level":"info","ts":"2026-07-14T17:48:42.113723Z","caller":"traceutil/trace.go:171","msg":"trace[634702891] range","detail":"{range_begin:/registry/pods/openstack/keystone-credential-setup-wdvjd; range_end:; response_count:1; response_revision:5338; }","duration":"270.560826ms","start":"2026-07-14T17:48:41.84314Z","end":"2026-07-14T17:48:42.113701Z","steps":["trace[634702891] 'range keys from in-memory index tree' (duration: 270.287708ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:42.647333Z","caller":"traceutil/trace.go:171","msg":"trace[277177786] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"521.978822ms","start":"2026-07-14T17:48:42.125329Z","end":"2026-07-14T17:48:42.647307Z","steps":["trace[277177786] 'process raft request' (duration: 521.828378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:42.647479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:48:42.125309Z","time spent":"522.098365ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7777,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:48:42.680216Z","caller":"traceutil/trace.go:171","msg":"trace[176331373] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"228.93148ms","start":"2026-07-14T17:48:42.451255Z","end":"2026-07-14T17:48:42.680186Z","steps":["trace[176331373] 'process raft request' (duration: 228.623781ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:42.680521Z","caller":"traceutil/trace.go:171","msg":"trace[1093092198] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"194.830478ms","start":"2026-07-14T17:48:42.485619Z","end":"2026-07-14T17:48:42.680449Z","steps":["trace[1093092198] 'process raft request' (duration: 194.415916ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:42.680563Z","caller":"traceutil/trace.go:171","msg":"trace[1044042850] transaction","detail":"{read_only:false; response_revision:5342; number_of_response:1; }","duration":"189.801638ms","start":"2026-07-14T17:48:42.490733Z","end":"2026-07-14T17:48:42.680535Z","steps":["trace[1044042850] 'process raft request' (duration: 189.649483ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:42.839254Z","caller":"traceutil/trace.go:171","msg":"trace[1836505268] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"144.032236ms","start":"2026-07-14T17:48:42.695197Z","end":"2026-07-14T17:48:42.83923Z","steps":["trace[1836505268] 'process raft request' (duration: 83.849093ms)","trace[1836505268] 'compare' (duration: 60.06039ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:48:51.90503Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2806} {"level":"info","ts":"2026-07-14T17:48:51.984707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2806,"took":"78.070877ms","hash":2020332287,"current-db-size-bytes":52305920,"current-db-size":"52 MB","current-db-size-in-use-bytes":28262400,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-14T17:48:51.984795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2020332287,"revision":2806,"compact-revision":-1} {"level":"warn","ts":"2026-07-14T17:48:53.982427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.720665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:48:53.982631Z","caller":"traceutil/trace.go:171","msg":"trace[845548364] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"252.784536ms","start":"2026-07-14T17:48:53.729835Z","end":"2026-07-14T17:48:53.982619Z","steps":["trace[845548364] 'process raft request' (duration: 252.753855ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:53.983083Z","caller":"traceutil/trace.go:171","msg":"trace[1034298817] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"253.433425ms","start":"2026-07-14T17:48:53.729635Z","end":"2026-07-14T17:48:53.983069Z","steps":["trace[1034298817] 'process raft request' (duration: 252.93131ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:53.98297Z","caller":"traceutil/trace.go:171","msg":"trace[1747915893] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"253.895569ms","start":"2026-07-14T17:48:53.729062Z","end":"2026-07-14T17:48:53.982957Z","steps":["trace[1747915893] 'process raft request' (duration: 57.538295ms)","trace[1747915893] 'compare' (duration: 195.617852ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:48:53.983729Z","caller":"traceutil/trace.go:171","msg":"trace[1187275793] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"254.472186ms","start":"2026-07-14T17:48:53.729237Z","end":"2026-07-14T17:48:53.983709Z","steps":["trace[1187275793] 'process raft request' (duration: 253.288591ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:54.071928Z","caller":"traceutil/trace.go:171","msg":"trace[187648102] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"162.612087ms","start":"2026-07-14T17:48:53.909279Z","end":"2026-07-14T17:48:54.071891Z","steps":["trace[187648102] 'process raft request' (duration: 162.39059ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:56.452604Z","caller":"traceutil/trace.go:171","msg":"trace[1654332363] linearizableReadLoop","detail":"{readStateIndex:5632; appliedIndex:5631; }","duration":"106.395089ms","start":"2026-07-14T17:48:56.346182Z","end":"2026-07-14T17:48:56.452577Z","steps":["trace[1654332363] 'read index received' (duration: 106.164763ms)","trace[1654332363] 'applied index is now lower than readState.Index' (duration: 229.016µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:48:56.45273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.530494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:48:56.452761Z","caller":"traceutil/trace.go:171","msg":"trace[892168226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5425; }","duration":"106.607445ms","start":"2026-07-14T17:48:56.346145Z","end":"2026-07-14T17:48:56.452752Z","steps":["trace[892168226] 'agreement among raft nodes before linearized reading' (duration: 106.533103ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:56.452924Z","caller":"traceutil/trace.go:171","msg":"trace[1046039082] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"176.601478ms","start":"2026-07-14T17:48:56.276279Z","end":"2026-07-14T17:48:56.45288Z","steps":["trace[1046039082] 'process raft request' (duration: 176.099213ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:49:09.075562Z","caller":"traceutil/trace.go:171","msg":"trace[925903450] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"210.527218ms","start":"2026-07-14T17:49:08.865016Z","end":"2026-07-14T17:49:09.075544Z","steps":["trace[925903450] 'process raft request' (duration: 210.376244ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:49:09.109447Z","caller":"traceutil/trace.go:171","msg":"trace[598608049] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"190.269514ms","start":"2026-07-14T17:49:08.919158Z","end":"2026-07-14T17:49:09.109427Z","steps":["trace[598608049] 'process raft request' (duration: 189.867672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:49:09.12572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.965798ms","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-07-14T17:49:09.125805Z","caller":"traceutil/trace.go:171","msg":"trace[139392168] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5519; }","duration":"112.076812ms","start":"2026-07-14T17:49:09.01371Z","end":"2026-07-14T17:49:09.125787Z","steps":["trace[139392168] 'agreement among raft nodes before linearized reading' (duration: 111.966019ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:53:51.911614Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5401} {"level":"info","ts":"2026-07-14T17:53:52.002754Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5401,"took":"89.585671ms","hash":1724395694,"current-db-size-bytes":52305920,"current-db-size":"52 MB","current-db-size-in-use-bytes":18485248,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-14T17:53:52.00293Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1724395694,"revision":5401,"compact-revision":2806}