{"level":"warn","ts":"2026-09-19T00:58:14.759696Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T00:58:14.759791Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.155:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.155:2380","--initial-cluster=instance=https://162.253.55.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.155: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-09-19T00:58:14.759982Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T00:58:14.760149Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.155:2380"]} {"level":"info","ts":"2026-09-19T00:58:14.76018Z","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-09-19T00:58:14.761206Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.155:2379"]} {"level":"info","ts":"2026-09-19T00:58:14.761322Z","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://162.253.55.155:2380"],"listen-peer-urls":["https://162.253.55.155:2380"],"advertise-client-urls":["https://162.253.55.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.155: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-09-19T00:58:14.766368Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.71628ms"} {"level":"info","ts":"2026-09-19T00:58:14.772754Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e7a3df655adff1be","cluster-id":"563ca9e04ae55d1c"} {"level":"info","ts":"2026-09-19T00:58:14.772817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7a3df655adff1be switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T00:58:14.772915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7a3df655adff1be became follower at term 0"} {"level":"info","ts":"2026-09-19T00:58:14.772965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e7a3df655adff1be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T00:58:14.77298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7a3df655adff1be became follower at term 1"} {"level":"info","ts":"2026-09-19T00:58:14.773062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7a3df655adff1be switched to configuration voters=(16691430270374506942)"} {"level":"warn","ts":"2026-09-19T00:58:14.779658Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T00:58:14.78126Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T00:58:14.78265Z","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-09-19T00:58:14.785122Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e7a3df655adff1be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T00:58:14.785305Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e7a3df655adff1be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T00:58:14.785392Z","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-09-19T00:58:14.785793Z","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-09-19T00:58:14.785815Z","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-09-19T00:58:14.786496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7a3df655adff1be switched to configuration voters=(16691430270374506942)"} {"level":"info","ts":"2026-09-19T00:58:14.786734Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"563ca9e04ae55d1c","local-member-id":"e7a3df655adff1be","added-peer-id":"e7a3df655adff1be","added-peer-peer-urls":["https://162.253.55.155:2380"]} {"level":"info","ts":"2026-09-19T00:58:14.789086Z","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-09-19T00:58:14.789289Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.155:2380"} {"level":"info","ts":"2026-09-19T00:58:14.789306Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.155:2380"} {"level":"info","ts":"2026-09-19T00:58:14.789483Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e7a3df655adff1be","initial-advertise-peer-urls":["https://162.253.55.155:2380"],"listen-peer-urls":["https://162.253.55.155:2380"],"advertise-client-urls":["https://162.253.55.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T00:58:14.789513Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T00:58:15.673444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7a3df655adff1be is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T00:58:15.673533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7a3df655adff1be became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T00:58:15.673583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7a3df655adff1be received MsgPreVoteResp from e7a3df655adff1be at term 1"} {"level":"info","ts":"2026-09-19T00:58:15.673603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7a3df655adff1be became candidate at term 2"} {"level":"info","ts":"2026-09-19T00:58:15.673618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7a3df655adff1be received MsgVoteResp from e7a3df655adff1be at term 2"} {"level":"info","ts":"2026-09-19T00:58:15.673633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7a3df655adff1be became leader at term 2"} {"level":"info","ts":"2026-09-19T00:58:15.67365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e7a3df655adff1be elected leader e7a3df655adff1be at term 2"} {"level":"info","ts":"2026-09-19T00:58:15.675058Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:58:15.676034Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e7a3df655adff1be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.155:2379]}","request-path":"/0/members/e7a3df655adff1be/attributes","cluster-id":"563ca9e04ae55d1c","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T00:58:15.676248Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T00:58:15.676279Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T00:58:15.676507Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T00:58:15.676561Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T00:58:15.676831Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"563ca9e04ae55d1c","local-member-id":"e7a3df655adff1be","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:58:15.676984Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:58:15.677025Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:58:15.680202Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T00:58:15.680294Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.155:2379"} {"level":"info","ts":"2026-09-19T00:58:19.948912Z","caller":"traceutil/trace.go:171","msg":"trace[566918954] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"100.096141ms","start":"2026-09-19T00:58:19.848775Z","end":"2026-09-19T00:58:19.948872Z","steps":["trace[566918954] 'process raft request' (duration: 66.618414ms)","trace[566918954] 'compare' (duration: 33.191737ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:23.933021Z","caller":"traceutil/trace.go:171","msg":"trace[548293318] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"113.836592ms","start":"2026-09-19T00:58:23.819151Z","end":"2026-09-19T00:58:23.932988Z","steps":["trace[548293318] 'process raft request' (duration: 113.656996ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:40.303625Z","caller":"traceutil/trace.go:171","msg":"trace[1033539922] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"120.486612ms","start":"2026-09-19T00:58:40.183111Z","end":"2026-09-19T00:58:40.303597Z","steps":["trace[1033539922] 'read index received' (duration: 120.319148ms)","trace[1033539922] 'applied index is now lower than readState.Index' (duration: 165.774µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:40.303678Z","caller":"traceutil/trace.go:171","msg":"trace[201406064] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"158.373545ms","start":"2026-09-19T00:58:40.145267Z","end":"2026-09-19T00:58:40.30364Z","steps":["trace[201406064] 'process raft request' (duration: 158.144369ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:40.303899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.764541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-09-19T00:58:40.304051Z","caller":"traceutil/trace.go:171","msg":"trace[341599098] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:435; }","duration":"120.979356ms","start":"2026-09-19T00:58:40.183054Z","end":"2026-09-19T00:58:40.304033Z","steps":["trace[341599098] 'agreement among raft nodes before linearized reading' (duration: 120.664318ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:52.356293Z","caller":"traceutil/trace.go:171","msg":"trace[540802334] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"160.080623ms","start":"2026-09-19T00:58:52.19618Z","end":"2026-09-19T00:58:52.356261Z","steps":["trace[540802334] 'process raft request' (duration: 159.875437ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:52.421683Z","caller":"traceutil/trace.go:171","msg":"trace[1915535029] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"221.587711ms","start":"2026-09-19T00:58:52.200062Z","end":"2026-09-19T00:58:52.421649Z","steps":["trace[1915535029] 'process raft request' (duration: 210.327573ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:52.574105Z","caller":"traceutil/trace.go:171","msg":"trace[327877204] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"131.942728ms","start":"2026-09-19T00:58:52.44212Z","end":"2026-09-19T00:58:52.574062Z","steps":["trace[327877204] 'process raft request' (duration: 129.419725ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:52.574174Z","caller":"traceutil/trace.go:171","msg":"trace[1588625418] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"130.101792ms","start":"2026-09-19T00:58:52.444029Z","end":"2026-09-19T00:58:52.57413Z","steps":["trace[1588625418] 'process raft request' (duration: 129.930488ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:52.57467Z","caller":"traceutil/trace.go:171","msg":"trace[1938812366] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"130.301629ms","start":"2026-09-19T00:58:52.444313Z","end":"2026-09-19T00:58:52.574615Z","steps":["trace[1938812366] 'process raft request' (duration: 129.701694ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:52.797862Z","caller":"traceutil/trace.go:171","msg":"trace[605363440] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"208.940939ms","start":"2026-09-19T00:58:52.588881Z","end":"2026-09-19T00:58:52.797821Z","steps":["trace[605363440] 'process raft request' (duration: 134.400678ms)","trace[605363440] 'compare' (duration: 74.295135ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:52.798126Z","caller":"traceutil/trace.go:171","msg":"trace[2001326] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"208.99036ms","start":"2026-09-19T00:58:52.589085Z","end":"2026-09-19T00:58:52.798076Z","steps":["trace[2001326] 'process raft request' (duration: 208.633351ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:52.798164Z","caller":"traceutil/trace.go:171","msg":"trace[959706040] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"206.268212ms","start":"2026-09-19T00:58:52.591868Z","end":"2026-09-19T00:58:52.798136Z","steps":["trace[959706040] 'process raft request' (duration: 206.133519ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:52.798167Z","caller":"traceutil/trace.go:171","msg":"trace[1405731655] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:534; }","duration":"206.123789ms","start":"2026-09-19T00:58:52.592007Z","end":"2026-09-19T00:58:52.798131Z","steps":["trace[1405731655] 'read index received' (duration: 74.813837ms)","trace[1405731655] 'applied index is now lower than readState.Index' (duration: 131.308632ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:52.798523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.482178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mcsc7\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-09-19T00:58:52.79859Z","caller":"traceutil/trace.go:171","msg":"trace[1879837306] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mcsc7; range_end:; response_count:1; response_revision:518; }","duration":"206.614901ms","start":"2026-09-19T00:58:52.591955Z","end":"2026-09-19T00:58:52.79857Z","steps":["trace[1879837306] 'agreement among raft nodes before linearized reading' (duration: 206.302873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:52.802871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.227487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-09-19T00:58:52.802959Z","caller":"traceutil/trace.go:171","msg":"trace[1758332606] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:519; }","duration":"209.35479ms","start":"2026-09-19T00:58:52.593566Z","end":"2026-09-19T00:58:52.802921Z","steps":["trace[1758332606] 'agreement among raft nodes before linearized reading' (duration: 209.064542ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:52.803069Z","caller":"traceutil/trace.go:171","msg":"trace[1357769484] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"128.652987ms","start":"2026-09-19T00:58:52.674373Z","end":"2026-09-19T00:58:52.803026Z","steps":["trace[1357769484] 'process raft request' (duration: 128.144513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:52.803352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.793294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-09-19T00:58:52.803422Z","caller":"traceutil/trace.go:171","msg":"trace[69373984] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:519; }","duration":"199.896775ms","start":"2026-09-19T00:58:52.603502Z","end":"2026-09-19T00:58:52.803399Z","steps":["trace[69373984] 'agreement among raft nodes before linearized reading' (duration: 199.779303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:52.803561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.365525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-09-19T00:58:52.803616Z","caller":"traceutil/trace.go:171","msg":"trace[376538940] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:519; }","duration":"142.492488ms","start":"2026-09-19T00:58:52.661108Z","end":"2026-09-19T00:58:52.803601Z","steps":["trace[376538940] 'agreement among raft nodes before linearized reading' (duration: 142.054518ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:52.914026Z","caller":"traceutil/trace.go:171","msg":"trace[281060565] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"102.568112ms","start":"2026-09-19T00:58:52.811431Z","end":"2026-09-19T00:58:52.913999Z","steps":["trace[281060565] 'process raft request' (duration: 92.804451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:52.914376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.631428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-09-19T00:58:52.914482Z","caller":"traceutil/trace.go:171","msg":"trace[742629217] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:522; }","duration":"107.812602ms","start":"2026-09-19T00:58:52.806644Z","end":"2026-09-19T00:58:52.914457Z","steps":["trace[742629217] 'agreement among raft nodes before linearized reading' (duration: 107.584017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:54.515399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.777168ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-19T00:58:54.640577Z","caller":"traceutil/trace.go:171","msg":"trace[940835626] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"178.530027ms","start":"2026-09-19T00:58:54.461993Z","end":"2026-09-19T00:58:54.640523Z","steps":["trace[940835626] 'process raft request' (duration: 155.783037ms)","trace[940835626] 'compare' (duration: 22.454333ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:54.640664Z","caller":"traceutil/trace.go:171","msg":"trace[1168451805] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"168.350032ms","start":"2026-09-19T00:58:54.472274Z","end":"2026-09-19T00:58:54.640624Z","steps":["trace[1168451805] 'process raft request' (duration: 168.232459ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.640706Z","caller":"traceutil/trace.go:171","msg":"trace[1415869503] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"159.14049ms","start":"2026-09-19T00:58:54.481533Z","end":"2026-09-19T00:58:54.640674Z","steps":["trace[1415869503] 'process raft request' (duration: 159.021377ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.640786Z","caller":"traceutil/trace.go:171","msg":"trace[25369416] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"159.149709ms","start":"2026-09-19T00:58:54.481624Z","end":"2026-09-19T00:58:54.640773Z","steps":["trace[25369416] 'process raft request' (duration: 158.985365ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.640889Z","caller":"traceutil/trace.go:171","msg":"trace[926735005] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"158.850452ms","start":"2026-09-19T00:58:54.48203Z","end":"2026-09-19T00:58:54.640881Z","steps":["trace[926735005] 'process raft request' (duration: 158.746889ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.640973Z","caller":"traceutil/trace.go:171","msg":"trace[837639740] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"149.863015ms","start":"2026-09-19T00:58:54.4911Z","end":"2026-09-19T00:58:54.640963Z","steps":["trace[837639740] 'process raft request' (duration: 149.726011ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.641079Z","caller":"traceutil/trace.go:171","msg":"trace[749531307] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"122.501462ms","start":"2026-09-19T00:58:54.518565Z","end":"2026-09-19T00:58:54.641066Z","steps":["trace[749531307] 'process raft request' (duration: 122.353538ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.641076Z","caller":"traceutil/trace.go:171","msg":"trace[677305817] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"159.000476ms","start":"2026-09-19T00:58:54.481882Z","end":"2026-09-19T00:58:54.640882Z","steps":["trace[677305817] 'process raft request' (duration: 158.77425ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.641066Z","caller":"traceutil/trace.go:171","msg":"trace[1368080621] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"121.17628ms","start":"2026-09-19T00:58:54.519861Z","end":"2026-09-19T00:58:54.641037Z","steps":["trace[1368080621] 'process raft request' (duration: 121.123889ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.641184Z","caller":"traceutil/trace.go:171","msg":"trace[1071041775] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"133.270423ms","start":"2026-09-19T00:58:54.507903Z","end":"2026-09-19T00:58:54.641173Z","steps":["trace[1071041775] 'process raft request' (duration: 132.976507ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.641337Z","caller":"traceutil/trace.go:171","msg":"trace[569483651] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"179.223195ms","start":"2026-09-19T00:58:54.462088Z","end":"2026-09-19T00:58:54.641311Z","steps":["trace[569483651] 'process raft request' (duration: 178.335803ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.709394Z","caller":"traceutil/trace.go:171","msg":"trace[978363112] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"163.654079ms","start":"2026-09-19T00:58:54.545707Z","end":"2026-09-19T00:58:54.709361Z","steps":["trace[978363112] 'process raft request' (duration: 163.352752ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.709558Z","caller":"traceutil/trace.go:171","msg":"trace[297491931] transaction","detail":"{read_only:false; number_of_response:1; response_revision:542; }","duration":"163.687159ms","start":"2026-09-19T00:58:54.545844Z","end":"2026-09-19T00:58:54.709531Z","steps":["trace[297491931] 'process raft request' (duration: 163.624057ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.709617Z","caller":"traceutil/trace.go:171","msg":"trace[1549159612] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"163.798102ms","start":"2026-09-19T00:58:54.545789Z","end":"2026-09-19T00:58:54.709587Z","steps":["trace[1549159612] 'process raft request' (duration: 163.477915ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.995577Z","caller":"traceutil/trace.go:171","msg":"trace[767531572] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"344.289438ms","start":"2026-09-19T00:58:54.651259Z","end":"2026-09-19T00:58:54.995549Z","steps":["trace[767531572] 'process raft request' (duration: 325.322509ms)","trace[767531572] 'compare' (duration: 18.612719ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:54.995668Z","caller":"traceutil/trace.go:171","msg":"trace[7761854] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"342.342171ms","start":"2026-09-19T00:58:54.653301Z","end":"2026-09-19T00:58:54.995643Z","steps":["trace[7761854] 'process raft request' (duration: 342.085504ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.995864Z","caller":"traceutil/trace.go:171","msg":"trace[2134554347] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"320.677446ms","start":"2026-09-19T00:58:54.675162Z","end":"2026-09-19T00:58:54.995839Z","steps":["trace[2134554347] 'process raft request' (duration: 320.617365ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.995974Z","caller":"traceutil/trace.go:171","msg":"trace[1084534680] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"341.615992ms","start":"2026-09-19T00:58:54.654351Z","end":"2026-09-19T00:58:54.995967Z","steps":["trace[1084534680] 'process raft request' (duration: 341.08622ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.996033Z","caller":"traceutil/trace.go:171","msg":"trace[509223571] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"326.321453ms","start":"2026-09-19T00:58:54.669693Z","end":"2026-09-19T00:58:54.996014Z","steps":["trace[509223571] 'process raft request' (duration: 325.873122ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:54.996099Z","caller":"traceutil/trace.go:171","msg":"trace[1213202774] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"330.651617ms","start":"2026-09-19T00:58:54.66544Z","end":"2026-09-19T00:58:54.996091Z","steps":["trace[1213202774] 'process raft request' (duration: 330.023473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:54.996098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:54.651232Z","time spent":"344.41003ms","remote":"127.0.0.1:59330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T00:58:54.996152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:54.665427Z","time spent":"330.694908ms","remote":"127.0.0.1:59330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T00:58:54.996189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:54.669669Z","time spent":"326.435966ms","remote":"127.0.0.1:59330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T00:58:54.996363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:54.653288Z","time spent":"342.518375ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:54.996398Z","caller":"traceutil/trace.go:171","msg":"trace[1708752295] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"332.136254ms","start":"2026-09-19T00:58:54.664207Z","end":"2026-09-19T00:58:54.996354Z","steps":["trace[1708752295] 'process raft request' (duration: 331.290164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:54.996457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:54.654319Z","time spent":"341.668364ms","remote":"127.0.0.1:59330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T00:58:54.996541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:54.675127Z","time spent":"320.81826ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:54.996048Z","caller":"traceutil/trace.go:171","msg":"trace[2003578049] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"321.069865ms","start":"2026-09-19T00:58:54.674967Z","end":"2026-09-19T00:58:54.996037Z","steps":["trace[2003578049] 'process raft request' (duration: 320.627735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:54.996547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:54.664178Z","time spent":"332.290787ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T00:58:54.996067Z","caller":"traceutil/trace.go:171","msg":"trace[105295813] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"320.982114ms","start":"2026-09-19T00:58:54.675081Z","end":"2026-09-19T00:58:54.996063Z","steps":["trace[105295813] 'process raft request' (duration: 320.561224ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:54.996627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:54.674942Z","time spent":"321.636329ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T00:58:54.996697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:54.675066Z","time spent":"321.588528ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:55.060431Z","caller":"traceutil/trace.go:171","msg":"trace[1520487301] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:564; }","duration":"243.805643ms","start":"2026-09-19T00:58:54.816594Z","end":"2026-09-19T00:58:55.060399Z","steps":["trace[1520487301] 'read index received' (duration: 160.00539ms)","trace[1520487301] 'applied index is now lower than readState.Index' (duration: 83.799013ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:55.060615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.996858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:58:55.060665Z","caller":"traceutil/trace.go:171","msg":"trace[1110385058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"244.10976ms","start":"2026-09-19T00:58:54.816542Z","end":"2026-09-19T00:58:55.060652Z","steps":["trace[1110385058] 'agreement among raft nodes before linearized reading' (duration: 243.976877ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:55.061211Z","caller":"traceutil/trace.go:171","msg":"trace[1406848152] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"347.645515ms","start":"2026-09-19T00:58:54.713549Z","end":"2026-09-19T00:58:55.061195Z","steps":["trace[1406848152] 'process raft request' (duration: 346.488328ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:55.061315Z","caller":"traceutil/trace.go:171","msg":"trace[85940352] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"336.279271ms","start":"2026-09-19T00:58:54.725003Z","end":"2026-09-19T00:58:55.061282Z","steps":["trace[85940352] 'process raft request' (duration: 335.266986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:55.061377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:54.713524Z","time spent":"347.773908ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T00:58:55.061482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:54.724978Z","time spent":"336.418654ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:55.061533Z","caller":"traceutil/trace.go:171","msg":"trace[1682084618] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"345.691968ms","start":"2026-09-19T00:58:54.71581Z","end":"2026-09-19T00:58:55.061502Z","steps":["trace[1682084618] 'process raft request' (duration: 344.390407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:55.061593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:54.715794Z","time spent":"345.77047ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T00:58:55.257303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.904794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:7352"} {"level":"info","ts":"2026-09-19T00:58:55.257621Z","caller":"traceutil/trace.go:171","msg":"trace[1597864111] transaction","detail":"{read_only:false; number_of_response:1; response_revision:554; }","duration":"250.453766ms","start":"2026-09-19T00:58:55.007116Z","end":"2026-09-19T00:58:55.25757Z","steps":["trace[1597864111] 'process raft request' (duration: 142.151572ms)","trace[1597864111] 'compare' (duration: 107.802461ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:55.257967Z","caller":"traceutil/trace.go:171","msg":"trace[97881241] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"224.947665ms","start":"2026-09-19T00:58:55.03298Z","end":"2026-09-19T00:58:55.257928Z","steps":["trace[97881241] 'process raft request' (duration: 224.544696ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:55.258094Z","caller":"traceutil/trace.go:171","msg":"trace[1912247981] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:576; }","duration":"195.346766ms","start":"2026-09-19T00:58:55.062675Z","end":"2026-09-19T00:58:55.258021Z","steps":["trace[1912247981] 'read index received' (duration: 86.692095ms)","trace[1912247981] 'applied index is now lower than readState.Index' (duration: 108.653541ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:55.258213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.51751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:58:55.258181Z","caller":"traceutil/trace.go:171","msg":"trace[1172492260] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"225.012188ms","start":"2026-09-19T00:58:55.033147Z","end":"2026-09-19T00:58:55.258159Z","steps":["trace[1172492260] 'process raft request' (duration: 224.66529ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:55.258271Z","caller":"traceutil/trace.go:171","msg":"trace[1063814459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"195.623803ms","start":"2026-09-19T00:58:55.062636Z","end":"2026-09-19T00:58:55.258259Z","steps":["trace[1063814459] 'agreement among raft nodes before linearized reading' (duration: 195.525301ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:55.299549Z","caller":"traceutil/trace.go:171","msg":"trace[506705857] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"219.002844ms","start":"2026-09-19T00:58:55.080514Z","end":"2026-09-19T00:58:55.299517Z","steps":["trace[506705857] 'process raft request' (duration: 218.765548ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:55.299878Z","caller":"traceutil/trace.go:171","msg":"trace[818627913] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"215.91645ms","start":"2026-09-19T00:58:55.083949Z","end":"2026-09-19T00:58:55.299865Z","steps":["trace[818627913] 'process raft request' (duration: 215.50629ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:55.300156Z","caller":"traceutil/trace.go:171","msg":"trace[178766669] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"196.817432ms","start":"2026-09-19T00:58:55.103315Z","end":"2026-09-19T00:58:55.300133Z","steps":["trace[178766669] 'process raft request' (duration: 196.567366ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:55.300581Z","caller":"traceutil/trace.go:171","msg":"trace[876877689] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"215.258263ms","start":"2026-09-19T00:58:55.085283Z","end":"2026-09-19T00:58:55.300541Z","steps":["trace[876877689] 'process raft request' (duration: 214.423384ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:55.637932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.107026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T00:58:55.638211Z","caller":"traceutil/trace.go:171","msg":"trace[1147385514] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"369.334583ms","start":"2026-09-19T00:58:55.268839Z","end":"2026-09-19T00:58:55.638174Z","steps":["trace[1147385514] 'process raft request' (duration: 240.845306ms)","trace[1147385514] 'compare' (duration: 127.921062ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:55.638316Z","caller":"traceutil/trace.go:171","msg":"trace[135388040] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"329.592742ms","start":"2026-09-19T00:58:55.308675Z","end":"2026-09-19T00:58:55.638268Z","steps":["trace[135388040] 'process raft request' (duration: 329.397426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:55.63844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.308643Z","time spent":"329.716785ms","remote":"127.0.0.1:59330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T00:58:55.638517Z","caller":"traceutil/trace.go:171","msg":"trace[388240787] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:583; }","duration":"320.529144ms","start":"2026-09-19T00:58:55.317917Z","end":"2026-09-19T00:58:55.638446Z","steps":["trace[388240787] 'read index received' (duration: 191.970476ms)","trace[388240787] 'applied index is now lower than readState.Index' (duration: 128.557198ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:55.638499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.268815Z","time spent":"369.486496ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:55.638537Z","caller":"traceutil/trace.go:171","msg":"trace[538467056] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"324.224693ms","start":"2026-09-19T00:58:55.314209Z","end":"2026-09-19T00:58:55.638434Z","steps":["trace[538467056] 'process raft request' (duration: 323.945986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:55.638684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.78502ms","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-09-19T00:58:55.638725Z","caller":"traceutil/trace.go:171","msg":"trace[983433143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"320.863282ms","start":"2026-09-19T00:58:55.31785Z","end":"2026-09-19T00:58:55.638713Z","steps":["trace[983433143] 'agreement among raft nodes before linearized reading' (duration: 320.77861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:55.6388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.317834Z","time spent":"320.956404ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T00:58:55.638906Z","caller":"traceutil/trace.go:171","msg":"trace[772216700] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"233.49754ms","start":"2026-09-19T00:58:55.40531Z","end":"2026-09-19T00:58:55.638807Z","steps":["trace[772216700] 'process raft request' (duration: 233.034049ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:55.638927Z","caller":"traceutil/trace.go:171","msg":"trace[1634712657] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"321.884636ms","start":"2026-09-19T00:58:55.316668Z","end":"2026-09-19T00:58:55.638552Z","steps":["trace[1634712657] 'process raft request' (duration: 321.580239ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:55.639203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.316616Z","time spent":"322.503471ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T00:58:55.638943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.314193Z","time spent":"324.50391ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T00:58:56.116728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.300567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T00:58:56.117139Z","caller":"traceutil/trace.go:171","msg":"trace[317213913] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"472.294361ms","start":"2026-09-19T00:58:55.644771Z","end":"2026-09-19T00:58:56.117065Z","steps":["trace[317213913] 'process raft request' (duration: 114.536032ms)","trace[317213913] 'compare' (duration: 357.146064ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:56.117411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.644708Z","time spent":"472.509696ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:56.117426Z","caller":"traceutil/trace.go:171","msg":"trace[1557321969] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"472.420724ms","start":"2026-09-19T00:58:55.644967Z","end":"2026-09-19T00:58:56.117387Z","steps":["trace[1557321969] 'process raft request' (duration: 471.910201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:56.117559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.644914Z","time spent":"472.580588ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:56.117874Z","caller":"traceutil/trace.go:171","msg":"trace[1908519651] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"458.785877ms","start":"2026-09-19T00:58:55.659068Z","end":"2026-09-19T00:58:56.117854Z","steps":["trace[1908519651] 'process raft request' (duration: 458.096391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:56.118038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.658987Z","time spent":"458.942071ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:56.118309Z","caller":"traceutil/trace.go:171","msg":"trace[89894941] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"451.886281ms","start":"2026-09-19T00:58:55.666404Z","end":"2026-09-19T00:58:56.118291Z","steps":["trace[89894941] 'process raft request' (duration: 451.282747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:56.118449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.666373Z","time spent":"451.982593ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:56.118554Z","caller":"traceutil/trace.go:171","msg":"trace[884400320] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"470.399185ms","start":"2026-09-19T00:58:55.648124Z","end":"2026-09-19T00:58:56.118524Z","steps":["trace[884400320] 'read index received' (duration: 111.266444ms)","trace[884400320] 'applied index is now lower than readState.Index' (duration: 359.131011ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:56.118769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.130531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T00:58:56.11897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.673471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T00:58:56.119039Z","caller":"traceutil/trace.go:171","msg":"trace[1456170625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"470.946328ms","start":"2026-09-19T00:58:55.648074Z","end":"2026-09-19T00:58:56.11902Z","steps":["trace[1456170625] 'agreement among raft nodes before linearized reading' (duration: 470.567829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:56.119275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.648054Z","time spent":"471.164932ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-19T00:58:56.118809Z","caller":"traceutil/trace.go:171","msg":"trace[781326589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"301.224704ms","start":"2026-09-19T00:58:55.817574Z","end":"2026-09-19T00:58:56.118798Z","steps":["trace[781326589] 'agreement among raft nodes before linearized reading' (duration: 301.116612ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:56.119822Z","caller":"traceutil/trace.go:171","msg":"trace[1508707925] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"385.906563ms","start":"2026-09-19T00:58:55.732588Z","end":"2026-09-19T00:58:56.118494Z","steps":["trace[1508707925] 'process raft request' (duration: 385.735588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:56.121303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.732549Z","time spent":"388.700609ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T00:58:56.119874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:55.817513Z","time spent":"302.257379ms","remote":"127.0.0.1:58796","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-09-19T00:58:56.325122Z","caller":"traceutil/trace.go:171","msg":"trace[711986754] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"189.129262ms","start":"2026-09-19T00:58:56.135964Z","end":"2026-09-19T00:58:56.325093Z","steps":["trace[711986754] 'process raft request' (duration: 188.848336ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:56.325502Z","caller":"traceutil/trace.go:171","msg":"trace[771566802] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"187.843342ms","start":"2026-09-19T00:58:56.137584Z","end":"2026-09-19T00:58:56.325427Z","steps":["trace[771566802] 'process raft request' (duration: 187.430532ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:56.575646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.037451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T00:58:56.575788Z","caller":"traceutil/trace.go:171","msg":"trace[236835906] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"364.15871ms","start":"2026-09-19T00:58:56.211607Z","end":"2026-09-19T00:58:56.575766Z","steps":["trace[236835906] 'process raft request' (duration: 252.917134ms)","trace[236835906] 'compare' (duration: 110.838056ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:56.575853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:56.211559Z","time spent":"364.266303ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T00:58:56.597082Z","caller":"traceutil/trace.go:171","msg":"trace[1059916406] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"252.587715ms","start":"2026-09-19T00:58:56.344401Z","end":"2026-09-19T00:58:56.596988Z","steps":["trace[1059916406] 'process raft request' (duration: 252.218267ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:56.597357Z","caller":"traceutil/trace.go:171","msg":"trace[822486529] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"250.273712ms","start":"2026-09-19T00:58:56.347026Z","end":"2026-09-19T00:58:56.5973Z","steps":["trace[822486529] 'process raft request' (duration: 249.863341ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:56.779836Z","caller":"traceutil/trace.go:171","msg":"trace[2139726153] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:599; }","duration":"125.044543ms","start":"2026-09-19T00:58:56.654767Z","end":"2026-09-19T00:58:56.779811Z","steps":["trace[2139726153] 'read index received' (duration: 29.931048ms)","trace[2139726153] 'applied index is now lower than readState.Index' (duration: 95.112445ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:56.779836Z","caller":"traceutil/trace.go:171","msg":"trace[1211760807] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"169.275012ms","start":"2026-09-19T00:58:56.610531Z","end":"2026-09-19T00:58:56.779806Z","steps":["trace[1211760807] 'process raft request' (duration: 74.071965ms)","trace[1211760807] 'compare' (duration: 94.793337ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:56.77993Z","caller":"traceutil/trace.go:171","msg":"trace[439920601] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"162.138673ms","start":"2026-09-19T00:58:56.617779Z","end":"2026-09-19T00:58:56.779918Z","steps":["trace[439920601] 'process raft request' (duration: 161.934338ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:56.779901Z","caller":"traceutil/trace.go:171","msg":"trace[1602388275] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"167.173761ms","start":"2026-09-19T00:58:56.612687Z","end":"2026-09-19T00:58:56.779861Z","steps":["trace[1602388275] 'process raft request' (duration: 166.942036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:56.780284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.517754ms","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-09-19T00:58:56.780382Z","caller":"traceutil/trace.go:171","msg":"trace[1489903832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"125.675637ms","start":"2026-09-19T00:58:56.654685Z","end":"2026-09-19T00:58:56.780361Z","steps":["trace[1489903832] 'agreement among raft nodes before linearized reading' (duration: 125.288218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:57.060691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.028162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T00:58:57.060835Z","caller":"traceutil/trace.go:171","msg":"trace[1865786353] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"262.690792ms","start":"2026-09-19T00:58:56.798123Z","end":"2026-09-19T00:58:57.060813Z","steps":["trace[1865786353] 'process raft request' (duration: 158.437474ms)","trace[1865786353] 'compare' (duration: 103.829908ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:57.064181Z","caller":"traceutil/trace.go:171","msg":"trace[1843803079] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"253.264619ms","start":"2026-09-19T00:58:56.810253Z","end":"2026-09-19T00:58:57.063518Z","steps":["trace[1843803079] 'process raft request' (duration: 253.009122ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:57.260951Z","caller":"traceutil/trace.go:171","msg":"trace[278219417] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"153.620484ms","start":"2026-09-19T00:58:57.106673Z","end":"2026-09-19T00:58:57.260294Z","steps":["trace[278219417] 'process raft request' (duration: 127.791209ms)","trace[278219417] 'compare' (duration: 25.683932ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:57.300443Z","caller":"traceutil/trace.go:171","msg":"trace[1324243285] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"156.282447ms","start":"2026-09-19T00:58:57.144133Z","end":"2026-09-19T00:58:57.300415Z","steps":["trace[1324243285] 'process raft request' (duration: 155.754184ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:57.4421Z","caller":"traceutil/trace.go:171","msg":"trace[1739257265] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"107.095823ms","start":"2026-09-19T00:58:57.334969Z","end":"2026-09-19T00:58:57.442065Z","steps":["trace[1739257265] 'process raft request' (duration: 84.015411ms)","trace[1739257265] 'compare' (duration: 22.923199ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:58.315072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17419537117289609702,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-19T00:58:58.602835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.592799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-09-19T00:58:58.602933Z","caller":"traceutil/trace.go:171","msg":"trace[1938105417] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:598; }","duration":"883.728582ms","start":"2026-09-19T00:58:57.719181Z","end":"2026-09-19T00:58:58.60291Z","steps":["trace[1938105417] 'range keys from in-memory index tree' (duration: 883.302322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:58.602973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:57.719167Z","time spent":"883.796954ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10893,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-09-19T00:58:58.653833Z","caller":"traceutil/trace.go:171","msg":"trace[232391745] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"923.925823ms","start":"2026-09-19T00:58:57.729881Z","end":"2026-09-19T00:58:58.653807Z","steps":["trace[232391745] 'process raft request' (duration: 922.48067ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:58.654004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:57.729864Z","time spent":"924.054626ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:58.823611Z","caller":"traceutil/trace.go:171","msg":"trace[2075527452] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:621; }","duration":"1.009381438s","start":"2026-09-19T00:58:57.814163Z","end":"2026-09-19T00:58:58.823544Z","steps":["trace[2075527452] 'read index received' (duration: 838.276293ms)","trace[2075527452] 'applied index is now lower than readState.Index' (duration: 171.104195ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:58.823776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009557022s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:58:58.823805Z","caller":"traceutil/trace.go:171","msg":"trace[1547211507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"1.009688705s","start":"2026-09-19T00:58:57.814108Z","end":"2026-09-19T00:58:58.823796Z","steps":["trace[1547211507] 'agreement among raft nodes before linearized reading' (duration: 1.009580733s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:58.823846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:57.814088Z","time spent":"1.009734617s","remote":"127.0.0.1:58796","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-09-19T00:58:58.823844Z","caller":"traceutil/trace.go:171","msg":"trace[668052050] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.000649004s","start":"2026-09-19T00:58:57.823136Z","end":"2026-09-19T00:58:58.823785Z","steps":["trace[668052050] 'process raft request' (duration: 999.804824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:58.824089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:57.823115Z","time spent":"1.000833279s","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:58.824089Z","caller":"traceutil/trace.go:171","msg":"trace[1539226394] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"748.268468ms","start":"2026-09-19T00:58:58.075721Z","end":"2026-09-19T00:58:58.82399Z","steps":["trace[1539226394] 'process raft request' (duration: 747.739077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:58.824516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:58.075696Z","time spent":"748.643648ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:58:58.873949Z","caller":"traceutil/trace.go:171","msg":"trace[46986832] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"178.115858ms","start":"2026-09-19T00:58:58.695806Z","end":"2026-09-19T00:58:58.873922Z","steps":["trace[46986832] 'process raft request' (duration: 177.652466ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:58.874491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.888074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-09-19T00:58:58.874542Z","caller":"traceutil/trace.go:171","msg":"trace[30771969] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:602; }","duration":"253.003326ms","start":"2026-09-19T00:58:58.621525Z","end":"2026-09-19T00:58:58.874528Z","steps":["trace[30771969] 'agreement among raft nodes before linearized reading' (duration: 252.856643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:58.874762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.25017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T00:58:58.874801Z","caller":"traceutil/trace.go:171","msg":"trace[1602029322] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"680.356142ms","start":"2026-09-19T00:58:58.194436Z","end":"2026-09-19T00:58:58.874792Z","steps":["trace[1602029322] 'agreement among raft nodes before linearized reading' (duration: 680.234759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:58.874829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:58.194417Z","time spent":"680.404143ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-19T00:58:58.875004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.653347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-19T00:58:58.87504Z","caller":"traceutil/trace.go:171","msg":"trace[868083461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"751.700318ms","start":"2026-09-19T00:58:58.123329Z","end":"2026-09-19T00:58:58.875029Z","steps":["trace[868083461] 'agreement among raft nodes before linearized reading' (duration: 751.618297ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:58.875069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:58.123324Z","time spent":"751.73957ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T00:58:58.875231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.936735ms","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-09-19T00:58:58.875265Z","caller":"traceutil/trace.go:171","msg":"trace[297541955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"752.008467ms","start":"2026-09-19T00:58:58.123249Z","end":"2026-09-19T00:58:58.875257Z","steps":["trace[297541955] 'agreement among raft nodes before linearized reading' (duration: 751.926974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:58.875288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:58.123229Z","time spent":"752.053178ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T00:58:58.999251Z","caller":"traceutil/trace.go:171","msg":"trace[787612066] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"108.177912ms","start":"2026-09-19T00:58:58.891038Z","end":"2026-09-19T00:58:58.999216Z","steps":["trace[787612066] 'process raft request' (duration: 66.799491ms)","trace[787612066] 'compare' (duration: 41.086054ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:58.999274Z","caller":"traceutil/trace.go:171","msg":"trace[1515230876] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"108.090381ms","start":"2026-09-19T00:58:58.891161Z","end":"2026-09-19T00:58:58.999251Z","steps":["trace[1515230876] 'process raft request' (duration: 107.966298ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:58.999592Z","caller":"traceutil/trace.go:171","msg":"trace[1934617484] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"108.308335ms","start":"2026-09-19T00:58:58.891269Z","end":"2026-09-19T00:58:58.999578Z","steps":["trace[1934617484] 'process raft request' (duration: 107.950057ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:59.000181Z","caller":"traceutil/trace.go:171","msg":"trace[1681675429] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"108.327896ms","start":"2026-09-19T00:58:58.891117Z","end":"2026-09-19T00:58:58.999445Z","steps":["trace[1681675429] 'process raft request' (duration: 107.963458ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:59.044641Z","caller":"traceutil/trace.go:171","msg":"trace[816026834] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"110.603089ms","start":"2026-09-19T00:58:58.934008Z","end":"2026-09-19T00:58:59.044611Z","steps":["trace[816026834] 'process raft request' (duration: 110.361034ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:59.177391Z","caller":"traceutil/trace.go:171","msg":"trace[2120642829] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"110.91426ms","start":"2026-09-19T00:58:59.066453Z","end":"2026-09-19T00:58:59.177367Z","steps":["trace[2120642829] 'process raft request' (duration: 110.743687ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:59.215794Z","caller":"traceutil/trace.go:171","msg":"trace[837771612] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"137.715127ms","start":"2026-09-19T00:58:59.078053Z","end":"2026-09-19T00:58:59.215768Z","steps":["trace[837771612] 'read index received' (duration: 99.366635ms)","trace[837771612] 'applied index is now lower than readState.Index' (duration: 38.347252ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:59.21583Z","caller":"traceutil/trace.go:171","msg":"trace[693785046] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"143.46112ms","start":"2026-09-19T00:58:59.072336Z","end":"2026-09-19T00:58:59.215797Z","steps":["trace[693785046] 'process raft request' (duration: 143.192674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:59.215949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.88475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-19T00:58:59.215994Z","caller":"traceutil/trace.go:171","msg":"trace[1338728512] range","detail":"{range_begin:/registry/masterleases/162.253.55.155; range_end:; response_count:1; response_revision:610; }","duration":"138.008884ms","start":"2026-09-19T00:58:59.077971Z","end":"2026-09-19T00:58:59.21598Z","steps":["trace[1338728512] 'agreement among raft nodes before linearized reading' (duration: 137.881131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:59.216027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.512978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-09-19T00:58:59.216085Z","caller":"traceutil/trace.go:171","msg":"trace[1843753095] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:610; }","duration":"109.642681ms","start":"2026-09-19T00:58:59.106427Z","end":"2026-09-19T00:58:59.21607Z","steps":["trace[1843753095] 'agreement among raft nodes before linearized reading' (duration: 109.508998ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:59.79448Z","caller":"traceutil/trace.go:171","msg":"trace[1800846414] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"152.847555ms","start":"2026-09-19T00:58:59.641611Z","end":"2026-09-19T00:58:59.794459Z","steps":["trace[1800846414] 'process raft request' (duration: 62.59124ms)","trace[1800846414] 'compare' (duration: 89.959818ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:59:03.636345Z","caller":"traceutil/trace.go:171","msg":"trace[1523976391] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"373.66546ms","start":"2026-09-19T00:59:03.262651Z","end":"2026-09-19T00:59:03.636317Z","steps":["trace[1523976391] 'process raft request' (duration: 373.553158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:03.636502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:59:03.262634Z","time spent":"373.811573ms","remote":"127.0.0.1:59110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T00:59:05.961464Z","caller":"traceutil/trace.go:171","msg":"trace[715611685] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"132.490502ms","start":"2026-09-19T00:59:05.828946Z","end":"2026-09-19T00:59:05.961436Z","steps":["trace[715611685] 'process raft request' (duration: 126.610704ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:59:06.610266Z","caller":"traceutil/trace.go:171","msg":"trace[504472643] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"229.168451ms","start":"2026-09-19T00:59:06.381073Z","end":"2026-09-19T00:59:06.610242Z","steps":["trace[504472643] 'process raft request' (duration: 228.995448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:07.117765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.37698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:59:07.117867Z","caller":"traceutil/trace.go:171","msg":"trace[3035474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"302.550575ms","start":"2026-09-19T00:59:06.81529Z","end":"2026-09-19T00:59:07.117841Z","steps":["trace[3035474] 'range keys from in-memory index tree' (duration: 302.251817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:07.117895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.548812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-19T00:59:07.117923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:59:06.815239Z","time spent":"302.668187ms","remote":"127.0.0.1:58796","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-09-19T00:59:07.117959Z","caller":"traceutil/trace.go:171","msg":"trace[1233233967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"137.650433ms","start":"2026-09-19T00:59:06.980291Z","end":"2026-09-19T00:59:07.117942Z","steps":["trace[1233233967] 'range keys from in-memory index tree' (duration: 137.333736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:07.714601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17419537117289609835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-19T00:59:08.215641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17419537117289609835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-19T00:59:08.242179Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.098316305s","expected-duration":"1s"} {"level":"warn","ts":"2026-09-19T00:59:08.371925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.378486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T00:59:08.372061Z","caller":"traceutil/trace.go:171","msg":"trace[1264040196] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"1.158513878s","start":"2026-09-19T00:59:07.213523Z","end":"2026-09-19T00:59:08.372036Z","steps":["trace[1264040196] 'read index received' (duration: 1.028756955s)","trace[1264040196] 'applied index is now lower than readState.Index' (duration: 129.755373ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:59:08.37242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.921001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:59:08.372446Z","caller":"traceutil/trace.go:171","msg":"trace[1250045634] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.22859918s","start":"2026-09-19T00:59:07.14376Z","end":"2026-09-19T00:59:08.372359Z","steps":["trace[1250045634] 'process raft request' (duration: 1.098630781s)","trace[1250045634] 'compare' (duration: 128.962987ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:59:08.372479Z","caller":"traceutil/trace.go:171","msg":"trace[1386863660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"556.020564ms","start":"2026-09-19T00:59:07.816438Z","end":"2026-09-19T00:59:08.372459Z","steps":["trace[1386863660] 'agreement among raft nodes before linearized reading' (duration: 555.914222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:08.372486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158934428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-09-19T00:59:08.372552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:59:07.143743Z","time spent":"1.228753974s","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:59:08.372593Z","caller":"traceutil/trace.go:171","msg":"trace[139125221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"1.159093171s","start":"2026-09-19T00:59:07.213473Z","end":"2026-09-19T00:59:08.372566Z","steps":["trace[139125221] 'agreement among raft nodes before linearized reading' (duration: 1.158704792s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:08.37252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:59:07.816401Z","time spent":"556.110316ms","remote":"127.0.0.1:58796","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-09-19T00:59:08.372652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:59:07.213452Z","time spent":"1.159185363s","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-19T00:59:08.372484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158842276s","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-09-19T00:59:08.37283Z","caller":"traceutil/trace.go:171","msg":"trace[456744322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"1.159231894s","start":"2026-09-19T00:59:07.213584Z","end":"2026-09-19T00:59:08.372815Z","steps":["trace[456744322] 'agreement among raft nodes before linearized reading' (duration: 1.158758405s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:08.372888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:59:07.213573Z","time spent":"1.159301085s","remote":"127.0.0.1:59046","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-09-19T00:59:10.784249Z","caller":"traceutil/trace.go:171","msg":"trace[1281895919] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"102.472879ms","start":"2026-09-19T00:59:10.681746Z","end":"2026-09-19T00:59:10.784219Z","steps":["trace[1281895919] 'process raft request' (duration: 102.344036ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:59:24.745294Z","caller":"traceutil/trace.go:171","msg":"trace[2146660786] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"197.283561ms","start":"2026-09-19T00:59:24.547975Z","end":"2026-09-19T00:59:24.745258Z","steps":["trace[2146660786] 'process raft request' (duration: 197.124779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:25.058032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.102014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:59:25.058152Z","caller":"traceutil/trace.go:171","msg":"trace[555595978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:820; }","duration":"241.217596ms","start":"2026-09-19T00:59:24.816903Z","end":"2026-09-19T00:59:25.058121Z","steps":["trace[555595978] 'range keys from in-memory index tree' (duration: 239.998673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:14.075223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.390434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:14.075369Z","caller":"traceutil/trace.go:171","msg":"trace[2119675031] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1368; }","duration":"156.588477ms","start":"2026-09-19T01:00:13.918758Z","end":"2026-09-19T01:00:14.075347Z","steps":["trace[2119675031] 'count revisions from in-memory index tree' (duration: 156.314933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:20.984646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.835429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:20.984738Z","caller":"traceutil/trace.go:171","msg":"trace[1907635416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1488; }","duration":"167.97998ms","start":"2026-09-19T01:00:20.816742Z","end":"2026-09-19T01:00:20.984722Z","steps":["trace[1907635416] 'range keys from in-memory index tree' (duration: 167.748677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.464577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.115077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.464675Z","caller":"traceutil/trace.go:171","msg":"trace[1654499348] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:1592; }","duration":"210.248228ms","start":"2026-09-19T01:00:27.254407Z","end":"2026-09-19T01:00:27.464655Z","steps":["trace[1654499348] 'range keys from in-memory index tree' (duration: 210.066417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.464656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.177853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.464742Z","caller":"traceutil/trace.go:171","msg":"trace[2103007432] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:1592; }","duration":"265.325464ms","start":"2026-09-19T01:00:27.199397Z","end":"2026-09-19T01:00:27.464722Z","steps":["trace[2103007432] 'count revisions from in-memory index tree' (duration: 265.066262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.464912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.040829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:00:27.464973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.734817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.464978Z","caller":"traceutil/trace.go:171","msg":"trace[1403051972] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:1592; }","duration":"192.1326ms","start":"2026-09-19T01:00:27.272827Z","end":"2026-09-19T01:00:27.46496Z","steps":["trace[1403051972] 'count revisions from in-memory index tree' (duration: 192.006778ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:27.465004Z","caller":"traceutil/trace.go:171","msg":"trace[1006724750] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:1592; }","duration":"264.781307ms","start":"2026-09-19T01:00:27.200214Z","end":"2026-09-19T01:00:27.464995Z","steps":["trace[1006724750] 'range keys from in-memory index tree' (duration: 264.696516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.465027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.761473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.46506Z","caller":"traceutil/trace.go:171","msg":"trace[649792779] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:1592; }","duration":"301.808884ms","start":"2026-09-19T01:00:27.16324Z","end":"2026-09-19T01:00:27.465049Z","steps":["trace[649792779] 'count revisions from in-memory index tree' (duration: 301.726492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.465094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:27.163235Z","time spent":"301.848754ms","remote":"127.0.0.1:59374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-19T01:00:27.46518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.987994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.46521Z","caller":"traceutil/trace.go:171","msg":"trace[944120820] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:1592; }","duration":"248.040785ms","start":"2026-09-19T01:00:27.21716Z","end":"2026-09-19T01:00:27.465201Z","steps":["trace[944120820] 'range keys from in-memory index tree' (duration: 247.893513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.465322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.529384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.465362Z","caller":"traceutil/trace.go:171","msg":"trace[1830919052] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:1592; }","duration":"192.592065ms","start":"2026-09-19T01:00:27.272759Z","end":"2026-09-19T01:00:27.465351Z","steps":["trace[1830919052] 'range keys from in-memory index tree' (duration: 192.332472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.465408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.216267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.465439Z","caller":"traceutil/trace.go:171","msg":"trace[1168170427] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:1592; }","duration":"248.263167ms","start":"2026-09-19T01:00:27.217167Z","end":"2026-09-19T01:00:27.46543Z","steps":["trace[1168170427] 'count revisions from in-memory index tree' (duration: 248.098335ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.465497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.449883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.465527Z","caller":"traceutil/trace.go:171","msg":"trace[33150694] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:1592; }","duration":"192.494073ms","start":"2026-09-19T01:00:27.273023Z","end":"2026-09-19T01:00:27.465517Z","steps":["trace[33150694] 'range keys from in-memory index tree' (duration: 192.412023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.465599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.930653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.465639Z","caller":"traceutil/trace.go:171","msg":"trace[1132505133] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:1592; }","duration":"247.985875ms","start":"2026-09-19T01:00:27.217641Z","end":"2026-09-19T01:00:27.465627Z","steps":["trace[1132505133] 'range keys from in-memory index tree' (duration: 247.895473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.465646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.289231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.465674Z","caller":"traceutil/trace.go:171","msg":"trace[1473293458] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:1592; }","duration":"148.331301ms","start":"2026-09-19T01:00:27.317334Z","end":"2026-09-19T01:00:27.465666Z","steps":["trace[1473293458] 'count revisions from in-memory index tree' (duration: 148.225669ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.465779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.518958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.46581Z","caller":"traceutil/trace.go:171","msg":"trace[125601334] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:1592; }","duration":"227.562998ms","start":"2026-09-19T01:00:27.238235Z","end":"2026-09-19T01:00:27.465798Z","steps":["trace[125601334] 'count revisions from in-memory index tree' (duration: 227.486637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.465837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.076976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.465862Z","caller":"traceutil/trace.go:171","msg":"trace[851560897] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:1592; }","duration":"147.128916ms","start":"2026-09-19T01:00:27.318724Z","end":"2026-09-19T01:00:27.465853Z","steps":["trace[851560897] 'range keys from in-memory index tree' (duration: 147.018696ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.465942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.74628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.465972Z","caller":"traceutil/trace.go:171","msg":"trace[8873539] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:1592; }","duration":"227.800561ms","start":"2026-09-19T01:00:27.238163Z","end":"2026-09-19T01:00:27.465964Z","steps":["trace[8873539] 'range keys from in-memory index tree' (duration: 227.636309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.466055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.282932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:00:27.466081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.297406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.466093Z","caller":"traceutil/trace.go:171","msg":"trace[1028248568] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:1592; }","duration":"115.321503ms","start":"2026-09-19T01:00:27.35076Z","end":"2026-09-19T01:00:27.466081Z","steps":["trace[1028248568] 'range keys from in-memory index tree' (duration: 115.215071ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:27.466106Z","caller":"traceutil/trace.go:171","msg":"trace[1949372744] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:1592; }","duration":"227.323596ms","start":"2026-09-19T01:00:27.238775Z","end":"2026-09-19T01:00:27.466098Z","steps":["trace[1949372744] 'range keys from in-memory index tree' (duration: 227.251164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.466268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.306002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.466299Z","caller":"traceutil/trace.go:171","msg":"trace[544642310] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:1592; }","duration":"115.359252ms","start":"2026-09-19T01:00:27.350929Z","end":"2026-09-19T01:00:27.466288Z","steps":["trace[544642310] 'count revisions from in-memory index tree' (duration: 115.215731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.466325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.126511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.466352Z","caller":"traceutil/trace.go:171","msg":"trace[1193987084] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:1592; }","duration":"212.179503ms","start":"2026-09-19T01:00:27.254164Z","end":"2026-09-19T01:00:27.466344Z","steps":["trace[1193987084] 'count revisions from in-memory index tree' (duration: 212.05318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.466402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.761655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.466428Z","caller":"traceutil/trace.go:171","msg":"trace[1128405291] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:1592; }","duration":"114.833067ms","start":"2026-09-19T01:00:27.351585Z","end":"2026-09-19T01:00:27.466418Z","steps":["trace[1128405291] 'range keys from in-memory index tree' (duration: 114.624364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.466461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.264883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.466486Z","caller":"traceutil/trace.go:171","msg":"trace[1175459908] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:1592; }","duration":"212.313514ms","start":"2026-09-19T01:00:27.254164Z","end":"2026-09-19T01:00:27.466478Z","steps":["trace[1175459908] 'range keys from in-memory index tree' (duration: 212.186352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.466631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.890589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:00:27.465809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.462382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.466701Z","caller":"traceutil/trace.go:171","msg":"trace[1776549871] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:1592; }","duration":"283.99534ms","start":"2026-09-19T01:00:27.182685Z","end":"2026-09-19T01:00:27.466681Z","steps":["trace[1776549871] 'range keys from in-memory index tree' (duration: 283.841608ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:27.46671Z","caller":"traceutil/trace.go:171","msg":"trace[1564967526] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:1592; }","duration":"149.390134ms","start":"2026-09-19T01:00:27.317307Z","end":"2026-09-19T01:00:27.466697Z","steps":["trace[1564967526] 'range keys from in-memory index tree' (duration: 148.369741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.466853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.203741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.466884Z","caller":"traceutil/trace.go:171","msg":"trace[512721737] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:1592; }","duration":"284.259893ms","start":"2026-09-19T01:00:27.182615Z","end":"2026-09-19T01:00:27.466875Z","steps":["trace[512721737] 'count revisions from in-memory index tree' (duration: 284.148221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.466882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.210457ms","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-09-19T01:00:27.466921Z","caller":"traceutil/trace.go:171","msg":"trace[1121446087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1592; }","duration":"291.281817ms","start":"2026-09-19T01:00:27.175628Z","end":"2026-09-19T01:00:27.46691Z","steps":["trace[1121446087] 'range keys from in-memory index tree' (duration: 291.079525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.466991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.317803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.467016Z","caller":"traceutil/trace.go:171","msg":"trace[164128300] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:1592; }","duration":"284.369034ms","start":"2026-09-19T01:00:27.182639Z","end":"2026-09-19T01:00:27.467008Z","steps":["trace[164128300] 'range keys from in-memory index tree' (duration: 284.269063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.467051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.193772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.467076Z","caller":"traceutil/trace.go:171","msg":"trace[1680648794] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:1592; }","duration":"303.236352ms","start":"2026-09-19T01:00:27.163831Z","end":"2026-09-19T01:00:27.467068Z","steps":["trace[1680648794] 'range keys from in-memory index tree' (duration: 303.148411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.467103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:27.163823Z","time spent":"303.269002ms","remote":"127.0.0.1:59374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" limit:500 "} {"level":"warn","ts":"2026-09-19T01:00:27.467321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.923216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.467363Z","caller":"traceutil/trace.go:171","msg":"trace[941415828] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:1592; }","duration":"267.984696ms","start":"2026-09-19T01:00:27.199365Z","end":"2026-09-19T01:00:27.46735Z","steps":["trace[941415828] 'range keys from in-memory index tree' (duration: 267.800415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.465333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.143637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:27.470046Z","caller":"traceutil/trace.go:171","msg":"trace[1414536754] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:1592; }","duration":"306.880827ms","start":"2026-09-19T01:00:27.163153Z","end":"2026-09-19T01:00:27.470034Z","steps":["trace[1414536754] 'range keys from in-memory index tree' (duration: 302.013816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.470083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:27.163117Z","time spent":"306.951007ms","remote":"127.0.0.1:59374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" limit:10000 "} {"level":"info","ts":"2026-09-19T01:00:27.77787Z","caller":"traceutil/trace.go:171","msg":"trace[1519792942] linearizableReadLoop","detail":"{readStateIndex:1646; appliedIndex:1645; }","duration":"153.588415ms","start":"2026-09-19T01:00:27.624258Z","end":"2026-09-19T01:00:27.777847Z","steps":["trace[1519792942] 'read index received' (duration: 151.809243ms)","trace[1519792942] 'applied index is now lower than readState.Index' (duration: 1.778242ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:00:27.77811Z","caller":"traceutil/trace.go:171","msg":"trace[1209171435] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1593; }","duration":"273.154338ms","start":"2026-09-19T01:00:27.504925Z","end":"2026-09-19T01:00:27.778079Z","steps":["trace[1209171435] 'process raft request' (duration: 271.225694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.778197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.925869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:00:27.778257Z","caller":"traceutil/trace.go:171","msg":"trace[1235780804] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1593; }","duration":"154.03895ms","start":"2026-09-19T01:00:27.624202Z","end":"2026-09-19T01:00:27.778241Z","steps":["trace[1235780804] 'agreement among raft nodes before linearized reading' (duration: 153.891618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:27.778258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.748325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-19T01:00:27.778336Z","caller":"traceutil/trace.go:171","msg":"trace[1126224294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1593; }","duration":"133.861167ms","start":"2026-09-19T01:00:27.644454Z","end":"2026-09-19T01:00:27.778315Z","steps":["trace[1126224294] 'agreement among raft nodes before linearized reading' (duration: 133.622824ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:39.654553Z","caller":"traceutil/trace.go:171","msg":"trace[412312164] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"121.132866ms","start":"2026-09-19T01:00:39.533391Z","end":"2026-09-19T01:00:39.654524Z","steps":["trace[412312164] 'process raft request' (duration: 120.946054ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:48.407795Z","caller":"traceutil/trace.go:171","msg":"trace[1350542103] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"134.359085ms","start":"2026-09-19T01:00:48.273397Z","end":"2026-09-19T01:00:48.407756Z","steps":["trace[1350542103] 'process raft request' (duration: 91.054176ms)","trace[1350542103] 'compare' (duration: 42.873545ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:00:49.200485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.290368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:49.200593Z","caller":"traceutil/trace.go:171","msg":"trace[232283825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1922; }","duration":"383.475719ms","start":"2026-09-19T01:00:48.817093Z","end":"2026-09-19T01:00:49.200569Z","steps":["trace[232283825] 'range keys from in-memory index tree' (duration: 383.111026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:49.200638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:48.817052Z","time spent":"383.576131ms","remote":"127.0.0.1:58796","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-09-19T01:00:49.200948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.075263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-19T01:00:49.201011Z","caller":"traceutil/trace.go:171","msg":"trace[1500424510] range","detail":"{range_begin:/registry/masterleases/162.253.55.155; range_end:; response_count:1; response_revision:1922; }","duration":"111.196314ms","start":"2026-09-19T01:00:49.089799Z","end":"2026-09-19T01:00:49.200995Z","steps":["trace[1500424510] 'range keys from in-memory index tree' (duration: 110.80566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:49.200999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.506836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d3cec00-d65a-48ff-ad73-5e8b92687268\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-19T01:00:49.201083Z","caller":"traceutil/trace.go:171","msg":"trace[769234037] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7d3cec00-d65a-48ff-ad73-5e8b92687268; range_end:; response_count:1; response_revision:1922; }","duration":"323.605627ms","start":"2026-09-19T01:00:48.877453Z","end":"2026-09-19T01:00:49.201058Z","steps":["trace[769234037] 'range keys from in-memory index tree' (duration: 323.256314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:49.201125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:48.877381Z","time spent":"323.73491ms","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d3cec00-d65a-48ff-ad73-5e8b92687268\" "} {"level":"warn","ts":"2026-09-19T01:00:49.201367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.571484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:49.201408Z","caller":"traceutil/trace.go:171","msg":"trace[1749904992] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1922; }","duration":"136.618385ms","start":"2026-09-19T01:00:49.064776Z","end":"2026-09-19T01:00:49.201394Z","steps":["trace[1749904992] 'count revisions from in-memory index tree' (duration: 136.394112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:49.201442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.277316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d3cec00-d65a-48ff-ad73-5e8b92687268\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-19T01:00:49.201482Z","caller":"traceutil/trace.go:171","msg":"trace[1188663185] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7d3cec00-d65a-48ff-ad73-5e8b92687268; range_end:; response_count:1; response_revision:1922; }","duration":"315.377536ms","start":"2026-09-19T01:00:48.886093Z","end":"2026-09-19T01:00:49.20147Z","steps":["trace[1188663185] 'range keys from in-memory index tree' (duration: 315.103393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:49.201512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:48.886057Z","time spent":"315.446629ms","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d3cec00-d65a-48ff-ad73-5e8b92687268\" "} {"level":"warn","ts":"2026-09-19T01:00:49.201657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.847933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T01:00:49.201711Z","caller":"traceutil/trace.go:171","msg":"trace[2052319623] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1922; }","duration":"287.981775ms","start":"2026-09-19T01:00:48.913713Z","end":"2026-09-19T01:00:49.201695Z","steps":["trace[2052319623] 'range keys from in-memory index tree' (duration: 287.679761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.334747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.004514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:00:50.335051Z","caller":"traceutil/trace.go:171","msg":"trace[1804167439] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"1.073261261s","start":"2026-09-19T01:00:49.261771Z","end":"2026-09-19T01:00:50.335032Z","steps":["trace[1804167439] 'process raft request' (duration: 1.073215531s)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:50.335044Z","caller":"traceutil/trace.go:171","msg":"trace[1499348589] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"1.123188581s","start":"2026-09-19T01:00:49.211818Z","end":"2026-09-19T01:00:50.335006Z","steps":["trace[1499348589] 'process raft request' (duration: 610.839334ms)","trace[1499348589] 'compare' (duration: 511.6661ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:00:50.335127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:49.261737Z","time spent":"1.073356992s","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:00:50.335251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:49.211792Z","time spent":"1.123361643s","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:00:50.335334Z","caller":"traceutil/trace.go:171","msg":"trace[2071909702] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"1.091324671s","start":"2026-09-19T01:00:49.243999Z","end":"2026-09-19T01:00:50.335324Z","steps":["trace[2071909702] 'process raft request' (duration: 1.090839045s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.335393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:49.24398Z","time spent":"1.091381452s","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:00:50.369615Z","caller":"traceutil/trace.go:171","msg":"trace[1165329546] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:1999; }","duration":"1.011882584s","start":"2026-09-19T01:00:49.357688Z","end":"2026-09-19T01:00:50.369571Z","steps":["trace[1165329546] 'read index received' (duration: 464.896377ms)","trace[1165329546] 'applied index is now lower than readState.Index' (duration: 546.984967ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:00:50.369743Z","caller":"traceutil/trace.go:171","msg":"trace[1808120300] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"1.105484765s","start":"2026-09-19T01:00:49.264227Z","end":"2026-09-19T01:00:50.369712Z","steps":["trace[1808120300] 'process raft request' (duration: 1.105109921s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.370284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:49.264212Z","time spent":"1.105934259s","remote":"127.0.0.1:58836","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-09-19T01:00:50.369719Z","caller":"traceutil/trace.go:171","msg":"trace[382020846] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"1.082711664s","start":"2026-09-19T01:00:49.286952Z","end":"2026-09-19T01:00:50.369664Z","steps":["trace[382020846] 'process raft request' (duration: 1.082539723s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.370721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:49.286923Z","time spent":"1.083665525s","remote":"127.0.0.1:59046","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-09-19T01:00:50.369944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012243579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T01:00:50.370834Z","caller":"traceutil/trace.go:171","msg":"trace[1198449960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1927; }","duration":"1.013212069s","start":"2026-09-19T01:00:49.357601Z","end":"2026-09-19T01:00:50.370813Z","steps":["trace[1198449960] 'agreement among raft nodes before linearized reading' (duration: 1.012138477s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.370921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:49.357581Z","time spent":"1.01332232s","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-19T01:00:50.402213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.16119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d3cec00-d65a-48ff-ad73-5e8b92687268\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-09-19T01:00:50.402305Z","caller":"traceutil/trace.go:171","msg":"trace[1134177980] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7d3cec00-d65a-48ff-ad73-5e8b92687268; range_end:; response_count:1; response_revision:1928; }","duration":"195.358683ms","start":"2026-09-19T01:00:50.20691Z","end":"2026-09-19T01:00:50.402269Z","steps":["trace[1134177980] 'agreement among raft nodes before linearized reading' (duration: 195.198011ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:50.402302Z","caller":"traceutil/trace.go:171","msg":"trace[1142734944] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"238.256793ms","start":"2026-09-19T01:00:50.163987Z","end":"2026-09-19T01:00:50.402244Z","steps":["trace[1142734944] 'process raft request' (duration: 237.94634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.402562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.111324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-19T01:00:50.402533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.968829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:50.402605Z","caller":"traceutil/trace.go:171","msg":"trace[127922820] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1928; }","duration":"146.190514ms","start":"2026-09-19T01:00:50.256403Z","end":"2026-09-19T01:00:50.402594Z","steps":["trace[127922820] 'agreement among raft nodes before linearized reading' (duration: 146.107223ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:50.402647Z","caller":"traceutil/trace.go:171","msg":"trace[1355127742] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:1928; }","duration":"670.168612ms","start":"2026-09-19T01:00:49.732448Z","end":"2026-09-19T01:00:50.402617Z","steps":["trace[1355127742] 'agreement among raft nodes before linearized reading' (duration: 669.891418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.402718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:49.732435Z","time spent":"670.266053ms","remote":"127.0.0.1:33072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-09-19T01:00:50.402812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.977237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T01:00:50.402842Z","caller":"traceutil/trace.go:171","msg":"trace[2130051352] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1928; }","duration":"193.044117ms","start":"2026-09-19T01:00:50.209788Z","end":"2026-09-19T01:00:50.402832Z","steps":["trace[2130051352] 'agreement among raft nodes before linearized reading' (duration: 192.932036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.402953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.537272ms","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-09-19T01:00:50.403019Z","caller":"traceutil/trace.go:171","msg":"trace[971591891] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1928; }","duration":"676.648122ms","start":"2026-09-19T01:00:49.726358Z","end":"2026-09-19T01:00:50.403006Z","steps":["trace[971591891] 'agreement among raft nodes before linearized reading' (duration: 676.359679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.403104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.752804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-19T01:00:50.403132Z","caller":"traceutil/trace.go:171","msg":"trace[1422425371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1928; }","duration":"448.809785ms","start":"2026-09-19T01:00:49.954315Z","end":"2026-09-19T01:00:50.403125Z","steps":["trace[1422425371] 'agreement among raft nodes before linearized reading' (duration: 448.741535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.403149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:49.954296Z","time spent":"448.848656ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T01:00:50.403244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.828785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"info","ts":"2026-09-19T01:00:50.403293Z","caller":"traceutil/trace.go:171","msg":"trace[1597759376] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1928; }","duration":"326.911465ms","start":"2026-09-19T01:00:50.076367Z","end":"2026-09-19T01:00:50.403279Z","steps":["trace[1597759376] 'agreement among raft nodes before linearized reading' (duration: 326.697343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.403327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:50.076339Z","time spent":"326.980495ms","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":10351,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-19T01:00:50.403429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042296237s","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-09-19T01:00:50.403491Z","caller":"traceutil/trace.go:171","msg":"trace[855577797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1928; }","duration":"1.042375388s","start":"2026-09-19T01:00:49.361099Z","end":"2026-09-19T01:00:50.403475Z","steps":["trace[855577797] 'agreement among raft nodes before linearized reading' (duration: 1.042201256s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.403539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:49.36109Z","time spent":"1.04243873s","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-19T01:00:50.4037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042694581s","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-09-19T01:00:50.403746Z","caller":"traceutil/trace.go:171","msg":"trace[666984647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1928; }","duration":"1.042766043s","start":"2026-09-19T01:00:49.360972Z","end":"2026-09-19T01:00:50.403738Z","steps":["trace[666984647] 'agreement among raft nodes before linearized reading' (duration: 1.042657062s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:50.403779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:49.36095Z","time spent":"1.042814813s","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T01:00:50.403825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:00:49.726334Z","time spent":"677.473621ms","remote":"127.0.0.1:59046","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-09-19T01:01:03.561591Z","caller":"traceutil/trace.go:171","msg":"trace[2129659392] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"147.096225ms","start":"2026-09-19T01:01:03.414462Z","end":"2026-09-19T01:01:03.561558Z","steps":["trace[2129659392] 'process raft request' (duration: 146.849162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:07.204982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17419537117289612638,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T01:01:07.486142Z","caller":"traceutil/trace.go:171","msg":"trace[1501739554] linearizableReadLoop","detail":"{readStateIndex:2145; appliedIndex:2144; }","duration":"781.914359ms","start":"2026-09-19T01:01:06.704203Z","end":"2026-09-19T01:01:07.486117Z","steps":["trace[1501739554] 'read index received' (duration: 730.522776ms)","trace[1501739554] 'applied index is now lower than readState.Index' (duration: 51.390313ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:01:07.486431Z","caller":"traceutil/trace.go:171","msg":"trace[1032760109] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"784.053212ms","start":"2026-09-19T01:01:06.702358Z","end":"2026-09-19T01:01:07.486411Z","steps":["trace[1032760109] 'process raft request' (duration: 732.460937ms)","trace[1032760109] 'compare' (duration: 50.919169ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:01:07.486566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:01:06.702298Z","time spent":"784.168733ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:01:07.486874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.675708ms","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-09-19T01:01:07.48692Z","caller":"traceutil/trace.go:171","msg":"trace[256213912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2065; }","duration":"783.540247ms","start":"2026-09-19T01:01:06.703366Z","end":"2026-09-19T01:01:07.486906Z","steps":["trace[256213912] 'agreement among raft nodes before linearized reading' (duration: 783.402336ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:07.486935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.530176ms","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-09-19T01:01:07.48698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:01:06.703354Z","time spent":"783.610318ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-19T01:01:07.487001Z","caller":"traceutil/trace.go:171","msg":"trace[492525507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2065; }","duration":"782.646857ms","start":"2026-09-19T01:01:06.704335Z","end":"2026-09-19T01:01:07.486982Z","steps":["trace[492525507] 'agreement among raft nodes before linearized reading' (duration: 782.472415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:07.487052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:01:06.704308Z","time spent":"782.732218ms","remote":"127.0.0.1:59046","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-09-19T01:01:07.487204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.895536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:01:07.487265Z","caller":"traceutil/trace.go:171","msg":"trace[1068368655] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2065; }","duration":"647.997196ms","start":"2026-09-19T01:01:06.839255Z","end":"2026-09-19T01:01:07.487252Z","steps":["trace[1068368655] 'agreement among raft nodes before linearized reading' (duration: 647.901295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:07.487311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:01:06.839232Z","time spent":"648.065858ms","remote":"127.0.0.1:33050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-09-19T01:01:29.990809Z","caller":"traceutil/trace.go:171","msg":"trace[1478795794] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2275; }","duration":"342.62491ms","start":"2026-09-19T01:01:29.648155Z","end":"2026-09-19T01:01:29.99078Z","steps":["trace[1478795794] 'read index received' (duration: 244.239795ms)","trace[1478795794] 'applied index is now lower than readState.Index' (duration: 98.383835ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:01:29.990958Z","caller":"traceutil/trace.go:171","msg":"trace[1109539583] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"352.166304ms","start":"2026-09-19T01:01:29.638773Z","end":"2026-09-19T01:01:29.990939Z","steps":["trace[1109539583] 'process raft request' (duration: 253.554886ms)","trace[1109539583] 'compare' (duration: 98.306044ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:01:29.991112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:01:29.63875Z","time spent":"352.243965ms","remote":"127.0.0.1:59046","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-09-19T01:01:29.991246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.353478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-19T01:01:29.991407Z","caller":"traceutil/trace.go:171","msg":"trace[808743223] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2190; }","duration":"236.59892ms","start":"2026-09-19T01:01:29.754787Z","end":"2026-09-19T01:01:29.991386Z","steps":["trace[808743223] 'agreement among raft nodes before linearized reading' (duration: 236.268237ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:29.991521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.358207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-19T01:01:29.991554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.934686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:29.991592Z","caller":"traceutil/trace.go:171","msg":"trace[1395173943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2190; }","duration":"343.468358ms","start":"2026-09-19T01:01:29.648109Z","end":"2026-09-19T01:01:29.991577Z","steps":["trace[1395173943] 'agreement among raft nodes before linearized reading' (duration: 343.304007ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:29.99161Z","caller":"traceutil/trace.go:171","msg":"trace[1947387984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2190; }","duration":"175.077827ms","start":"2026-09-19T01:01:29.816514Z","end":"2026-09-19T01:01:29.991591Z","steps":["trace[1947387984] 'agreement among raft nodes before linearized reading' (duration: 174.913716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:29.991633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:01:29.64809Z","time spent":"343.531758ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-19T01:01:30.735793Z","caller":"traceutil/trace.go:171","msg":"trace[688840146] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"163.147877ms","start":"2026-09-19T01:01:30.572623Z","end":"2026-09-19T01:01:30.735771Z","steps":["trace[688840146] 'process raft request' (duration: 163.001395ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:30.917349Z","caller":"traceutil/trace.go:171","msg":"trace[274551255] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"322.850078ms","start":"2026-09-19T01:01:30.594459Z","end":"2026-09-19T01:01:30.917309Z","steps":["trace[274551255] 'process raft request' (duration: 322.746607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:30.917501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:01:30.594444Z","time spent":"322.974438ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:01:30.917537Z","caller":"traceutil/trace.go:171","msg":"trace[828013186] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2278; }","duration":"267.525647ms","start":"2026-09-19T01:01:30.649989Z","end":"2026-09-19T01:01:30.917514Z","steps":["trace[828013186] 'read index received' (duration: 85.644908ms)","trace[828013186] 'applied index is now lower than readState.Index' (duration: 181.879279ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:01:30.917672Z","caller":"traceutil/trace.go:171","msg":"trace[1720839463] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"337.777924ms","start":"2026-09-19T01:01:30.57985Z","end":"2026-09-19T01:01:30.917628Z","steps":["trace[1720839463] 'process raft request' (duration: 337.202449ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:30.917731Z","caller":"traceutil/trace.go:171","msg":"trace[26197991] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"321.550886ms","start":"2026-09-19T01:01:30.596168Z","end":"2026-09-19T01:01:30.917719Z","steps":["trace[26197991] 'process raft request' (duration: 321.110101ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:30.917837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:01:30.579829Z","time spent":"337.921587ms","remote":"127.0.0.1:59046","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-09-19T01:01:30.917955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.404787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T01:01:30.917993Z","caller":"traceutil/trace.go:171","msg":"trace[643569725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2196; }","duration":"262.462269ms","start":"2026-09-19T01:01:30.65552Z","end":"2026-09-19T01:01:30.917982Z","steps":["trace[643569725] 'agreement among raft nodes before linearized reading' (duration: 262.342218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:30.91799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.095562ms","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":"warn","ts":"2026-09-19T01:01:30.917993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:01:30.596151Z","time spent":"321.610977ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:01:30.918036Z","caller":"traceutil/trace.go:171","msg":"trace[1291959173] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2196; }","duration":"135.180003ms","start":"2026-09-19T01:01:30.782844Z","end":"2026-09-19T01:01:30.918024Z","steps":["trace[1291959173] 'agreement among raft nodes before linearized reading' (duration: 135.081842ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:30.918133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.049512ms","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-09-19T01:01:30.91817Z","caller":"traceutil/trace.go:171","msg":"trace[45105303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2196; }","duration":"178.120222ms","start":"2026-09-19T01:01:30.740038Z","end":"2026-09-19T01:01:30.918158Z","steps":["trace[45105303] 'agreement among raft nodes before linearized reading' (duration: 178.029521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:30.918279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.286325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-09-19T01:01:30.918308Z","caller":"traceutil/trace.go:171","msg":"trace[1537295094] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2196; }","duration":"268.343605ms","start":"2026-09-19T01:01:30.649955Z","end":"2026-09-19T01:01:30.918299Z","steps":["trace[1537295094] 'agreement among raft nodes before linearized reading' (duration: 268.229084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:32.001986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.032037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:01:32.002041Z","caller":"traceutil/trace.go:171","msg":"trace[1529581911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2199; }","duration":"184.155878ms","start":"2026-09-19T01:01:31.817873Z","end":"2026-09-19T01:01:32.002029Z","steps":["trace[1529581911] 'range keys from in-memory index tree' (duration: 183.969957ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:32.255637Z","caller":"traceutil/trace.go:171","msg":"trace[474780653] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2285; }","duration":"229.659597ms","start":"2026-09-19T01:01:32.025956Z","end":"2026-09-19T01:01:32.255615Z","steps":["trace[474780653] 'read index received' (duration: 229.503955ms)","trace[474780653] 'applied index is now lower than readState.Index' (duration: 154.492µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:01:32.255702Z","caller":"traceutil/trace.go:171","msg":"trace[219445058] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"249.798393ms","start":"2026-09-19T01:01:32.005881Z","end":"2026-09-19T01:01:32.255679Z","steps":["trace[219445058] 'process raft request' (duration: 249.598142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:01:32.255926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.871699ms","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-09-19T01:01:32.256113Z","caller":"traceutil/trace.go:171","msg":"trace[1329035795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2200; }","duration":"230.156301ms","start":"2026-09-19T01:01:32.025939Z","end":"2026-09-19T01:01:32.256095Z","steps":["trace[1329035795] 'agreement among raft nodes before linearized reading' (duration: 229.783768ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:32.30223Z","caller":"traceutil/trace.go:171","msg":"trace[111134382] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"100.742091ms","start":"2026-09-19T01:01:32.201465Z","end":"2026-09-19T01:01:32.302207Z","steps":["trace[111134382] 'process raft request' (duration: 100.702351ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:01:32.302681Z","caller":"traceutil/trace.go:171","msg":"trace[893175058] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"129.28655ms","start":"2026-09-19T01:01:32.173296Z","end":"2026-09-19T01:01:32.302582Z","steps":["trace[893175058] 'process raft request' (duration: 128.765084ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:02.556591Z","caller":"traceutil/trace.go:171","msg":"trace[247887872] transaction","detail":"{read_only:false; response_revision:2359; number_of_response:1; }","duration":"106.871249ms","start":"2026-09-19T01:02:02.4497Z","end":"2026-09-19T01:02:02.556571Z","steps":["trace[247887872] 'process raft request' (duration: 106.762798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:05.52369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.056014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:02:05.523879Z","caller":"traceutil/trace.go:171","msg":"trace[1458968989] linearizableReadLoop","detail":"{readStateIndex:2469; appliedIndex:2466; }","duration":"414.422888ms","start":"2026-09-19T01:02:05.109415Z","end":"2026-09-19T01:02:05.523838Z","steps":["trace[1458968989] 'read index received' (duration: 4.890066ms)","trace[1458968989] 'applied index is now lower than readState.Index' (duration: 409.532022ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:02:05.523935Z","caller":"traceutil/trace.go:171","msg":"trace[1519189620] transaction","detail":"{read_only:false; response_revision:2375; number_of_response:1; }","duration":"476.760541ms","start":"2026-09-19T01:02:05.047166Z","end":"2026-09-19T01:02:05.523927Z","steps":["trace[1519189620] 'process raft request' (duration: 316.404635ms)","trace[1519189620] 'compare' (duration: 159.61125ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:02:05.523972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:02:05.047144Z","time spent":"476.806542ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:02:05.524089Z","caller":"traceutil/trace.go:171","msg":"trace[461273574] transaction","detail":"{read_only:false; response_revision:2376; number_of_response:1; }","duration":"476.393199ms","start":"2026-09-19T01:02:05.047665Z","end":"2026-09-19T01:02:05.524058Z","steps":["trace[461273574] 'process raft request' (duration: 476.109196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:05.524164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.76697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-19T01:02:05.524185Z","caller":"traceutil/trace.go:171","msg":"trace[1176459739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2376; }","duration":"414.824191ms","start":"2026-09-19T01:02:05.109354Z","end":"2026-09-19T01:02:05.524178Z","steps":["trace[1176459739] 'agreement among raft nodes before linearized reading' (duration: 414.74218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:05.524202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:02:05.109335Z","time spent":"414.863301ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-19T01:02:05.52422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:02:05.047655Z","time spent":"476.497119ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:02:05.769313Z","caller":"traceutil/trace.go:171","msg":"trace[1854900198] transaction","detail":"{read_only:false; response_revision:2378; number_of_response:1; }","duration":"241.559824ms","start":"2026-09-19T01:02:05.527711Z","end":"2026-09-19T01:02:05.769271Z","steps":["trace[1854900198] 'process raft request' (duration: 241.497023ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:05.769311Z","caller":"traceutil/trace.go:171","msg":"trace[219260760] transaction","detail":"{read_only:false; response_revision:2377; number_of_response:1; }","duration":"366.370654ms","start":"2026-09-19T01:02:05.402911Z","end":"2026-09-19T01:02:05.769281Z","steps":["trace[219260760] 'process raft request' (duration: 311.456179ms)","trace[219260760] 'compare' (duration: 54.739523ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:02:05.769903Z","caller":"traceutil/trace.go:171","msg":"trace[785094985] linearizableReadLoop","detail":"{readStateIndex:2470; appliedIndex:2469; }","duration":"245.991626ms","start":"2026-09-19T01:02:05.523903Z","end":"2026-09-19T01:02:05.769895Z","steps":["trace[785094985] 'read index received' (duration: 190.466855ms)","trace[785094985] 'applied index is now lower than readState.Index' (duration: 55.524081ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:02:05.770114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.091438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-09-19T01:02:05.770099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.963394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-19T01:02:05.77015Z","caller":"traceutil/trace.go:171","msg":"trace[1473620935] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2378; }","duration":"537.192498ms","start":"2026-09-19T01:02:05.232947Z","end":"2026-09-19T01:02:05.770139Z","steps":["trace[1473620935] 'agreement among raft nodes before linearized reading' (duration: 536.991306ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:05.770082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.75561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:02:05.770177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:02:05.232926Z","time spent":"537.244268ms","remote":"127.0.0.1:39814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-19T01:02:05.770169Z","caller":"traceutil/trace.go:171","msg":"trace[107739349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2378; }","duration":"653.066825ms","start":"2026-09-19T01:02:05.117084Z","end":"2026-09-19T01:02:05.77015Z","steps":["trace[107739349] 'agreement among raft nodes before linearized reading' (duration: 652.878304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:05.770219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:02:05.117064Z","time spent":"653.143266ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T01:02:05.770214Z","caller":"traceutil/trace.go:171","msg":"trace[393205895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2378; }","duration":"172.931293ms","start":"2026-09-19T01:02:05.597252Z","end":"2026-09-19T01:02:05.770183Z","steps":["trace[393205895] 'agreement among raft nodes before linearized reading' (duration: 172.728411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:05.769812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:02:05.402839Z","time spent":"366.89923ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:02:05.957894Z","caller":"traceutil/trace.go:171","msg":"trace[1172660287] linearizableReadLoop","detail":"{readStateIndex:2473; appliedIndex:2472; }","duration":"141.836207ms","start":"2026-09-19T01:02:05.816033Z","end":"2026-09-19T01:02:05.95787Z","steps":["trace[1172660287] 'read index received' (duration: 122.70104ms)","trace[1172660287] 'applied index is now lower than readState.Index' (duration: 19.133987ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:02:05.958028Z","caller":"traceutil/trace.go:171","msg":"trace[1023413816] transaction","detail":"{read_only:false; response_revision:2380; number_of_response:1; }","duration":"170.942525ms","start":"2026-09-19T01:02:05.787055Z","end":"2026-09-19T01:02:05.957997Z","steps":["trace[1023413816] 'process raft request' (duration: 151.763938ms)","trace[1023413816] 'compare' (duration: 18.574452ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:02:05.958142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.952328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:02:05.958179Z","caller":"traceutil/trace.go:171","msg":"trace[904415607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2380; }","duration":"142.181559ms","start":"2026-09-19T01:02:05.815987Z","end":"2026-09-19T01:02:05.958168Z","steps":["trace[904415607] 'agreement among raft nodes before linearized reading' (duration: 141.964187ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:09.791002Z","caller":"traceutil/trace.go:171","msg":"trace[477346102] transaction","detail":"{read_only:false; response_revision:2396; number_of_response:1; }","duration":"252.12244ms","start":"2026-09-19T01:02:09.538833Z","end":"2026-09-19T01:02:09.790956Z","steps":["trace[477346102] 'process raft request' (duration: 162.196715ms)","trace[477346102] 'compare' (duration: 89.292429ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:02:10.123125Z","caller":"traceutil/trace.go:171","msg":"trace[1072369597] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"322.844737ms","start":"2026-09-19T01:02:09.800255Z","end":"2026-09-19T01:02:10.1231Z","steps":["trace[1072369597] 'process raft request' (duration: 322.696556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:10.123293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:02:09.80024Z","time spent":"322.957479ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:02:10.123601Z","caller":"traceutil/trace.go:171","msg":"trace[196900593] linearizableReadLoop","detail":"{readStateIndex:2492; appliedIndex:2492; }","duration":"278.085146ms","start":"2026-09-19T01:02:09.845478Z","end":"2026-09-19T01:02:10.123563Z","steps":["trace[196900593] 'read index received' (duration: 278.077746ms)","trace[196900593] 'applied index is now lower than readState.Index' (duration: 6.11µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:02:10.123736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.257838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:02:10.123814Z","caller":"traceutil/trace.go:171","msg":"trace[1490481584] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2398; }","duration":"278.351559ms","start":"2026-09-19T01:02:09.845422Z","end":"2026-09-19T01:02:10.123774Z","steps":["trace[1490481584] 'agreement among raft nodes before linearized reading' (duration: 278.270278ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:10.140455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.492821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-19T01:02:10.140575Z","caller":"traceutil/trace.go:171","msg":"trace[951203839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2399; }","duration":"169.674783ms","start":"2026-09-19T01:02:09.97087Z","end":"2026-09-19T01:02:10.140545Z","steps":["trace[951203839] 'agreement among raft nodes before linearized reading' (duration: 169.104059ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:14.890962Z","caller":"traceutil/trace.go:171","msg":"trace[606758482] linearizableReadLoop","detail":"{readStateIndex:2514; appliedIndex:2513; }","duration":"180.757428ms","start":"2026-09-19T01:02:14.710157Z","end":"2026-09-19T01:02:14.890914Z","steps":["trace[606758482] 'read index received' (duration: 176.831025ms)","trace[606758482] 'applied index is now lower than readState.Index' (duration: 3.924983ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:02:14.891244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.070983ms","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-09-19T01:02:14.891292Z","caller":"traceutil/trace.go:171","msg":"trace[2078515471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2419; }","duration":"181.196024ms","start":"2026-09-19T01:02:14.710081Z","end":"2026-09-19T01:02:14.891278Z","steps":["trace[2078515471] 'agreement among raft nodes before linearized reading' (duration: 180.996231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:14.891572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.736302ms","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-09-19T01:02:14.891816Z","caller":"traceutil/trace.go:171","msg":"trace[260733906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2419; }","duration":"121.919125ms","start":"2026-09-19T01:02:14.769746Z","end":"2026-09-19T01:02:14.891665Z","steps":["trace[260733906] 'agreement among raft nodes before linearized reading' (duration: 121.431858ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:15.094589Z","caller":"traceutil/trace.go:171","msg":"trace[192553173] transaction","detail":"{read_only:false; response_revision:2420; number_of_response:1; }","duration":"198.519583ms","start":"2026-09-19T01:02:14.896025Z","end":"2026-09-19T01:02:15.094545Z","steps":["trace[192553173] 'process raft request' (duration: 198.231568ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:15.101598Z","caller":"traceutil/trace.go:171","msg":"trace[844593801] transaction","detail":"{read_only:false; response_revision:2421; number_of_response:1; }","duration":"203.399751ms","start":"2026-09-19T01:02:14.898171Z","end":"2026-09-19T01:02:15.101571Z","steps":["trace[844593801] 'process raft request' (duration: 203.179321ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:15.101832Z","caller":"traceutil/trace.go:171","msg":"trace[680741593] transaction","detail":"{read_only:false; response_revision:2422; number_of_response:1; }","duration":"203.602312ms","start":"2026-09-19T01:02:14.898197Z","end":"2026-09-19T01:02:15.101799Z","steps":["trace[680741593] 'process raft request' (duration: 203.314228ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:15.315322Z","caller":"traceutil/trace.go:171","msg":"trace[724566555] transaction","detail":"{read_only:false; response_revision:2423; number_of_response:1; }","duration":"155.803845ms","start":"2026-09-19T01:02:15.159452Z","end":"2026-09-19T01:02:15.315256Z","steps":["trace[724566555] 'process raft request' (duration: 148.537236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:39.955161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.942501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:02:39.95555Z","caller":"traceutil/trace.go:171","msg":"trace[514404055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2568; }","duration":"141.355319ms","start":"2026-09-19T01:02:39.814165Z","end":"2026-09-19T01:02:39.95552Z","steps":["trace[514404055] 'range keys from in-memory index tree' (duration: 140.887759ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:40.145283Z","caller":"traceutil/trace.go:171","msg":"trace[1161712592] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"171.172972ms","start":"2026-09-19T01:02:39.974089Z","end":"2026-09-19T01:02:40.145262Z","steps":["trace[1161712592] 'process raft request' (duration: 168.07821ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:48.602849Z","caller":"traceutil/trace.go:171","msg":"trace[81733204] transaction","detail":"{read_only:false; response_revision:2652; number_of_response:1; }","duration":"116.125952ms","start":"2026-09-19T01:02:48.486694Z","end":"2026-09-19T01:02:48.60282Z","steps":["trace[81733204] 'process raft request' (duration: 115.974666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:58.069722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.203536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-19T01:02:58.069827Z","caller":"traceutil/trace.go:171","msg":"trace[1505523401] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2758; }","duration":"156.354922ms","start":"2026-09-19T01:02:57.913448Z","end":"2026-09-19T01:02:58.069803Z","steps":["trace[1505523401] 'range keys from in-memory index tree' (duration: 155.946145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:58.070562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.563726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-19T01:02:58.070685Z","caller":"traceutil/trace.go:171","msg":"trace[1652799204] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2758; }","duration":"123.742504ms","start":"2026-09-19T01:02:57.946918Z","end":"2026-09-19T01:02:58.070661Z","steps":["trace[1652799204] 'range keys from in-memory index tree' (duration: 123.366129ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:58.642657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.331641ms","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-09-19T01:02:58.642705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.852723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T01:02:58.642767Z","caller":"traceutil/trace.go:171","msg":"trace[1197465007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2759; }","duration":"274.508887ms","start":"2026-09-19T01:02:58.368223Z","end":"2026-09-19T01:02:58.642732Z","steps":["trace[1197465007] 'range keys from in-memory index tree' (duration: 274.138384ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:58.642782Z","caller":"traceutil/trace.go:171","msg":"trace[1966690445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2759; }","duration":"408.981288ms","start":"2026-09-19T01:02:58.233782Z","end":"2026-09-19T01:02:58.642763Z","steps":["trace[1966690445] 'range keys from in-memory index tree' (duration: 408.646235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:58.642825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:02:58.233762Z","time spent":"409.053981ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-19T01:02:58.642836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.828836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-09-19T01:02:58.642894Z","caller":"traceutil/trace.go:171","msg":"trace[1640874043] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2759; }","duration":"443.9126ms","start":"2026-09-19T01:02:58.198966Z","end":"2026-09-19T01:02:58.642879Z","steps":["trace[1640874043] 'range keys from in-memory index tree' (duration: 443.707362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:58.642896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.836362ms","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-09-19T01:02:58.642925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:02:58.198953Z","time spent":"443.964921ms","remote":"127.0.0.1:39814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5674,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-19T01:02:58.642966Z","caller":"traceutil/trace.go:171","msg":"trace[1558911224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2759; }","duration":"273.923435ms","start":"2026-09-19T01:02:58.369024Z","end":"2026-09-19T01:02:58.642948Z","steps":["trace[1558911224] 'range keys from in-memory index tree' (duration: 273.734338ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:30.15354Z","caller":"traceutil/trace.go:171","msg":"trace[1592538772] linearizableReadLoop","detail":"{readStateIndex:3048; appliedIndex:3047; }","duration":"206.763604ms","start":"2026-09-19T01:03:29.946752Z","end":"2026-09-19T01:03:30.153515Z","steps":["trace[1592538772] 'read index received' (duration: 206.654821ms)","trace[1592538772] 'applied index is now lower than readState.Index' (duration: 107.873µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:03:30.15358Z","caller":"traceutil/trace.go:171","msg":"trace[1511337989] transaction","detail":"{read_only:false; response_revision:2936; number_of_response:1; }","duration":"274.155133ms","start":"2026-09-19T01:03:29.879409Z","end":"2026-09-19T01:03:30.153565Z","steps":["trace[1511337989] 'process raft request' (duration: 273.954346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:03:30.153715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.950681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-19T01:03:30.15375Z","caller":"traceutil/trace.go:171","msg":"trace[719911754] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2936; }","duration":"207.035394ms","start":"2026-09-19T01:03:29.946705Z","end":"2026-09-19T01:03:30.15374Z","steps":["trace[719911754] 'agreement among raft nodes before linearized reading' (duration: 206.892829ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:03:30.439446Z","caller":"traceutil/trace.go:171","msg":"trace[850698547] transaction","detail":"{read_only:false; response_revision:2937; number_of_response:1; }","duration":"262.528836ms","start":"2026-09-19T01:03:30.176891Z","end":"2026-09-19T01:03:30.43942Z","steps":["trace[850698547] 'process raft request' (duration: 198.053865ms)","trace[850698547] 'compare' (duration: 64.354227ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:03:35.8187Z","caller":"traceutil/trace.go:171","msg":"trace[1263407727] transaction","detail":"{read_only:false; response_revision:2967; number_of_response:1; }","duration":"180.634697ms","start":"2026-09-19T01:03:35.638027Z","end":"2026-09-19T01:03:35.818662Z","steps":["trace[1263407727] 'process raft request' (duration: 180.434561ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:03:36.210642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.30089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:03:36.210718Z","caller":"traceutil/trace.go:171","msg":"trace[2050207919] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2967; }","duration":"298.425444ms","start":"2026-09-19T01:03:35.912272Z","end":"2026-09-19T01:03:36.210697Z","steps":["trace[2050207919] 'count revisions from in-memory index tree' (duration: 298.232039ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:03:36.210804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.99842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-19T01:03:36.210868Z","caller":"traceutil/trace.go:171","msg":"trace[1312407826] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2967; }","duration":"265.100813ms","start":"2026-09-19T01:03:35.945751Z","end":"2026-09-19T01:03:36.210852Z","steps":["trace[1312407826] 'range keys from in-memory index tree' (duration: 264.821444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:03:36.210919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.165361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:03:36.210981Z","caller":"traceutil/trace.go:171","msg":"trace[1076733835] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2967; }","duration":"317.268374ms","start":"2026-09-19T01:03:35.893694Z","end":"2026-09-19T01:03:36.210963Z","steps":["trace[1076733835] 'count revisions from in-memory index tree' (duration: 317.09869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:03:36.211014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:03:35.893678Z","time spent":"317.327107ms","remote":"127.0.0.1:59482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"info","ts":"2026-09-19T01:07:08.74995Z","caller":"traceutil/trace.go:171","msg":"trace[588462559] transaction","detail":"{read_only:false; response_revision:4174; number_of_response:1; }","duration":"129.353243ms","start":"2026-09-19T01:07:08.62054Z","end":"2026-09-19T01:07:08.749893Z","steps":["trace[588462559] 'process raft request' (duration: 95.47739ms)","trace[588462559] 'compare' (duration: 33.67878ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:08.750413Z","caller":"traceutil/trace.go:171","msg":"trace[583799475] transaction","detail":"{read_only:false; response_revision:4175; number_of_response:1; }","duration":"126.165439ms","start":"2026-09-19T01:07:08.624231Z","end":"2026-09-19T01:07:08.750396Z","steps":["trace[583799475] 'process raft request' (duration: 125.61039ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:08.75068Z","caller":"traceutil/trace.go:171","msg":"trace[1318225099] transaction","detail":"{read_only:false; response_revision:4176; number_of_response:1; }","duration":"112.753916ms","start":"2026-09-19T01:07:08.637892Z","end":"2026-09-19T01:07:08.750646Z","steps":["trace[1318225099] 'process raft request' (duration: 112.324589ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:08.750795Z","caller":"traceutil/trace.go:171","msg":"trace[543503414] transaction","detail":"{read_only:false; response_revision:4177; number_of_response:1; }","duration":"112.502751ms","start":"2026-09-19T01:07:08.63828Z","end":"2026-09-19T01:07:08.750783Z","steps":["trace[543503414] 'process raft request' (duration: 112.049454ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:16.182696Z","caller":"traceutil/trace.go:171","msg":"trace[1620871791] transaction","detail":"{read_only:false; response_revision:4273; number_of_response:1; }","duration":"103.298295ms","start":"2026-09-19T01:07:16.079372Z","end":"2026-09-19T01:07:16.18267Z","steps":["trace[1620871791] 'process raft request' (duration: 103.107382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:05.322258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.23888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:08:05.322547Z","caller":"traceutil/trace.go:171","msg":"trace[1140040402] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"167.446606ms","start":"2026-09-19T01:08:05.154985Z","end":"2026-09-19T01:08:05.322431Z","steps":["trace[1140040402] 'process raft request' (duration: 22.933232ms)","trace[1140040402] 'compare' (duration: 143.748502ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:12.627828Z","caller":"traceutil/trace.go:171","msg":"trace[762641939] transaction","detail":"{read_only:false; response_revision:5125; number_of_response:1; }","duration":"201.808287ms","start":"2026-09-19T01:08:12.426003Z","end":"2026-09-19T01:08:12.627812Z","steps":["trace[762641939] 'process raft request' (duration: 201.633714ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:12.627806Z","caller":"traceutil/trace.go:171","msg":"trace[688895137] linearizableReadLoop","detail":"{readStateIndex:5324; appliedIndex:5323; }","duration":"141.588961ms","start":"2026-09-19T01:08:12.486196Z","end":"2026-09-19T01:08:12.627785Z","steps":["trace[688895137] 'read index received' (duration: 141.429439ms)","trace[688895137] 'applied index is now lower than readState.Index' (duration: 158.692µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:08:12.62797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.772055ms","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-09-19T01:08:12.628013Z","caller":"traceutil/trace.go:171","msg":"trace[2000748725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5125; }","duration":"141.867966ms","start":"2026-09-19T01:08:12.486136Z","end":"2026-09-19T01:08:12.628004Z","steps":["trace[2000748725] 'agreement among raft nodes before linearized reading' (duration: 141.714504ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:12.934562Z","caller":"traceutil/trace.go:171","msg":"trace[1707600593] linearizableReadLoop","detail":"{readStateIndex:5325; appliedIndex:5324; }","duration":"306.562589ms","start":"2026-09-19T01:08:12.627963Z","end":"2026-09-19T01:08:12.934526Z","steps":["trace[1707600593] 'read index received' (duration: 256.496078ms)","trace[1707600593] 'applied index is now lower than readState.Index' (duration: 50.064851ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:12.934629Z","caller":"traceutil/trace.go:171","msg":"trace[763272332] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"390.20085ms","start":"2026-09-19T01:08:12.544396Z","end":"2026-09-19T01:08:12.934596Z","steps":["trace[763272332] 'process raft request' (duration: 340.023247ms)","trace[763272332] 'compare' (duration: 49.881109ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:08:12.934689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.124279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:08:12.934793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:12.544368Z","time spent":"390.318782ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:08:12.934849Z","caller":"traceutil/trace.go:171","msg":"trace[1704087765] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:5126; }","duration":"313.324112ms","start":"2026-09-19T01:08:12.621504Z","end":"2026-09-19T01:08:12.934828Z","steps":["trace[1704087765] 'agreement among raft nodes before linearized reading' (duration: 313.073549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:12.935033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:12.621488Z","time spent":"313.520915ms","remote":"127.0.0.1:34032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-09-19T01:08:12.93504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.291629ms","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-09-19T01:08:12.935086Z","caller":"traceutil/trace.go:171","msg":"trace[1444150582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5126; }","duration":"378.36249ms","start":"2026-09-19T01:08:12.556712Z","end":"2026-09-19T01:08:12.935074Z","steps":["trace[1444150582] 'agreement among raft nodes before linearized reading' (duration: 377.962674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:12.935115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:12.556703Z","time spent":"378.40487ms","remote":"127.0.0.1:59046","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-09-19T01:08:12.980586Z","caller":"traceutil/trace.go:171","msg":"trace[1864264381] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"348.840283ms","start":"2026-09-19T01:08:12.631721Z","end":"2026-09-19T01:08:12.980561Z","steps":["trace[1864264381] 'process raft request' (duration: 348.585688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:12.980717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:12.631705Z","time spent":"348.943054ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:08:13.422872Z","caller":"traceutil/trace.go:171","msg":"trace[11845116] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"325.836097ms","start":"2026-09-19T01:08:13.097022Z","end":"2026-09-19T01:08:13.422858Z","steps":["trace[11845116] 'process raft request' (duration: 325.619613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:13.422996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:13.096987Z","time spent":"325.933518ms","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13278,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:08:13.423349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.578976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-19T01:08:13.423475Z","caller":"traceutil/trace.go:171","msg":"trace[575050346] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5133; }","duration":"316.683197ms","start":"2026-09-19T01:08:13.106737Z","end":"2026-09-19T01:08:13.42342Z","steps":["trace[575050346] 'agreement among raft nodes before linearized reading' (duration: 316.447124ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:13.42354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:13.106716Z","time spent":"316.809359ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-09-19T01:08:13.422794Z","caller":"traceutil/trace.go:171","msg":"trace[1560776208] linearizableReadLoop","detail":"{readStateIndex:5332; appliedIndex:5330; }","duration":"316.021887ms","start":"2026-09-19T01:08:13.106751Z","end":"2026-09-19T01:08:13.422773Z","steps":["trace[1560776208] 'read index received' (duration: 3.622604ms)","trace[1560776208] 'applied index is now lower than readState.Index' (duration: 312.398183ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:13.495273Z","caller":"traceutil/trace.go:171","msg":"trace[522820917] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"303.100861ms","start":"2026-09-19T01:08:13.192139Z","end":"2026-09-19T01:08:13.49524Z","steps":["trace[522820917] 'process raft request' (duration: 300.513992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:13.495397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:13.192115Z","time spent":"303.214892ms","remote":"127.0.0.1:59446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:08:13.495453Z","caller":"traceutil/trace.go:171","msg":"trace[652394683] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"141.870074ms","start":"2026-09-19T01:08:13.353546Z","end":"2026-09-19T01:08:13.495416Z","steps":["trace[652394683] 'process raft request' (duration: 141.61079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:13.495727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.17378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-19T01:08:13.495759Z","caller":"traceutil/trace.go:171","msg":"trace[142062562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5136; }","duration":"123.260421ms","start":"2026-09-19T01:08:13.372489Z","end":"2026-09-19T01:08:13.495749Z","steps":["trace[142062562] 'agreement among raft nodes before linearized reading' (duration: 123.131178ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:13.610272Z","caller":"traceutil/trace.go:171","msg":"trace[1985714739] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"111.604073ms","start":"2026-09-19T01:08:13.498595Z","end":"2026-09-19T01:08:13.610199Z","steps":["trace[1985714739] 'process raft request' (duration: 109.468111ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:13.610298Z","caller":"traceutil/trace.go:171","msg":"trace[1394823894] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"111.201397ms","start":"2026-09-19T01:08:13.49907Z","end":"2026-09-19T01:08:13.610271Z","steps":["trace[1394823894] 'process raft request' (duration: 111.008055ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:13.610375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.565058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:08:13.610452Z","caller":"traceutil/trace.go:171","msg":"trace[1152365637] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:0; response_revision:5142; }","duration":"110.66323ms","start":"2026-09-19T01:08:13.499749Z","end":"2026-09-19T01:08:13.610413Z","steps":["trace[1152365637] 'agreement among raft nodes before linearized reading' (duration: 110.538309ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:15.713361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2859} {"level":"info","ts":"2026-09-19T01:08:15.787183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2859,"took":"72.920934ms","hash":4148144790,"current-db-size-bytes":52322304,"current-db-size":"52 MB","current-db-size-in-use-bytes":27435008,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-19T01:08:15.787256Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4148144790,"revision":2859,"compact-revision":-1} {"level":"info","ts":"2026-09-19T01:08:17.350605Z","caller":"traceutil/trace.go:171","msg":"trace[122522991] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"225.67683ms","start":"2026-09-19T01:08:17.124905Z","end":"2026-09-19T01:08:17.350582Z","steps":["trace[122522991] 'process raft request' (duration: 225.574559ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:23.471233Z","caller":"traceutil/trace.go:171","msg":"trace[1822821446] transaction","detail":"{read_only:false; response_revision:5342; number_of_response:1; }","duration":"156.071693ms","start":"2026-09-19T01:08:23.315137Z","end":"2026-09-19T01:08:23.471208Z","steps":["trace[1822821446] 'process raft request' (duration: 145.530336ms)","trace[1822821446] 'compare' (duration: 10.352845ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:23.471464Z","caller":"traceutil/trace.go:171","msg":"trace[686059680] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"104.367137ms","start":"2026-09-19T01:08:23.367066Z","end":"2026-09-19T01:08:23.471434Z","steps":["trace[686059680] 'process raft request' (duration: 104.086863ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:23.805216Z","caller":"traceutil/trace.go:171","msg":"trace[232994591] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"119.580056ms","start":"2026-09-19T01:08:23.685612Z","end":"2026-09-19T01:08:23.805192Z","steps":["trace[232994591] 'process raft request' (duration: 119.407123ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:23.827828Z","caller":"traceutil/trace.go:171","msg":"trace[929939897] transaction","detail":"{read_only:false; response_revision:5347; number_of_response:1; }","duration":"140.938146ms","start":"2026-09-19T01:08:23.686867Z","end":"2026-09-19T01:08:23.827805Z","steps":["trace[929939897] 'process raft request' (duration: 140.778144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:30.317318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17419537117289620695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-19T01:08:30.412313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17853651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-09-19T01:08:30.412396Z","caller":"traceutil/trace.go:171","msg":"trace[337647978] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5387; }","duration":"1.178683482s","start":"2026-09-19T01:08:29.233691Z","end":"2026-09-19T01:08:30.412374Z","steps":["trace[337647978] 'range keys from in-memory index tree' (duration: 1.178364858s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:30.412446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:29.233673Z","time spent":"1.178760754s","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-19T01:08:30.412574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123652021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-19T01:08:30.41265Z","caller":"traceutil/trace.go:171","msg":"trace[1498177605] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5387; }","duration":"1.123776663s","start":"2026-09-19T01:08:29.288855Z","end":"2026-09-19T01:08:30.412632Z","steps":["trace[1498177605] 'range keys from in-memory index tree' (duration: 1.123479899s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:30.412693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:29.288835Z","time spent":"1.123848174s","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-19T01:08:30.412899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019890592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:08:30.412951Z","caller":"traceutil/trace.go:171","msg":"trace[1090677277] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:5387; }","duration":"1.020026714s","start":"2026-09-19T01:08:29.39291Z","end":"2026-09-19T01:08:30.412937Z","steps":["trace[1090677277] 'count revisions from in-memory index tree' (duration: 1.01976923s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:30.413086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:29.392887Z","time spent":"1.020181597s","remote":"127.0.0.1:59072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":30,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-09-19T01:08:30.431191Z","caller":"traceutil/trace.go:171","msg":"trace[1653809150] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"930.811154ms","start":"2026-09-19T01:08:29.50036Z","end":"2026-09-19T01:08:30.431171Z","steps":["trace[1653809150] 'process raft request' (duration: 930.617301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:30.431479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:29.500324Z","time spent":"931.022796ms","remote":"127.0.0.1:59046","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-09-19T01:08:30.703548Z","caller":"traceutil/trace.go:171","msg":"trace[114206952] linearizableReadLoop","detail":"{readStateIndex:5599; appliedIndex:5596; }","duration":"886.361795ms","start":"2026-09-19T01:08:29.81716Z","end":"2026-09-19T01:08:30.703522Z","steps":["trace[114206952] 'read index received' (duration: 613.838612ms)","trace[114206952] 'applied index is now lower than readState.Index' (duration: 272.522233ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:30.703722Z","caller":"traceutil/trace.go:171","msg":"trace[728356145] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"852.750884ms","start":"2026-09-19T01:08:29.850949Z","end":"2026-09-19T01:08:30.7037Z","steps":["trace[728356145] 'process raft request' (duration: 852.375999ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:30.703716Z","caller":"traceutil/trace.go:171","msg":"trace[1319594028] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"278.271868ms","start":"2026-09-19T01:08:30.425408Z","end":"2026-09-19T01:08:30.703679Z","steps":["trace[1319594028] 'process raft request' (duration: 278.049045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:30.703834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:29.850925Z","time spent":"852.845815ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:08:30.703881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.213311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-964a880c-eb4e-433a-bb60-2395e2a8ddda\" ","response":"range_response_count:1 size:1322"} {"level":"warn","ts":"2026-09-19T01:08:30.703871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.951411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-19T01:08:30.703915Z","caller":"traceutil/trace.go:171","msg":"trace[2055451306] range","detail":"{range_begin:/registry/persistentvolumes/pvc-964a880c-eb4e-433a-bb60-2395e2a8ddda; range_end:; response_count:1; response_revision:5391; }","duration":"287.272072ms","start":"2026-09-19T01:08:30.416631Z","end":"2026-09-19T01:08:30.703904Z","steps":["trace[2055451306] 'agreement among raft nodes before linearized reading' (duration: 287.14152ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:30.704012Z","caller":"traceutil/trace.go:171","msg":"trace[422658557] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"1.117942381s","start":"2026-09-19T01:08:29.586057Z","end":"2026-09-19T01:08:30.703999Z","steps":["trace[422658557] 'process raft request' (duration: 1.103017418s)","trace[422658557] 'compare' (duration: 13.869027ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:30.70402Z","caller":"traceutil/trace.go:171","msg":"trace[572978113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5391; }","duration":"864.096852ms","start":"2026-09-19T01:08:29.839824Z","end":"2026-09-19T01:08:30.703921Z","steps":["trace[572978113] 'agreement among raft nodes before linearized reading' (duration: 863.880829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:30.704069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:29.586028Z","time spent":"1.118007471s","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:08:30.704142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.83014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-09-19T01:08:30.704145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.162999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:08:30.704173Z","caller":"traceutil/trace.go:171","msg":"trace[765742664] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5391; }","duration":"179.929631ms","start":"2026-09-19T01:08:30.524235Z","end":"2026-09-19T01:08:30.704165Z","steps":["trace[765742664] 'agreement among raft nodes before linearized reading' (duration: 179.83848ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:30.704193Z","caller":"traceutil/trace.go:171","msg":"trace[420276315] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5391; }","duration":"623.24615ms","start":"2026-09-19T01:08:30.08093Z","end":"2026-09-19T01:08:30.704176Z","steps":["trace[420276315] 'agreement among raft nodes before linearized reading' (duration: 623.175029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:30.704079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:29.839802Z","time spent":"864.256265ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-19T01:08:30.704231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:30.080911Z","time spent":"623.309102ms","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-09-19T01:08:30.703735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.554558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:08:30.70445Z","caller":"traceutil/trace.go:171","msg":"trace[659605187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5391; }","duration":"887.323329ms","start":"2026-09-19T01:08:29.817116Z","end":"2026-09-19T01:08:30.70444Z","steps":["trace[659605187] 'agreement among raft nodes before linearized reading' (duration: 886.553738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:30.704484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:29.817096Z","time spent":"887.377359ms","remote":"127.0.0.1:58796","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-09-19T01:08:30.704562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.602926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-19T01:08:30.704609Z","caller":"traceutil/trace.go:171","msg":"trace[105616244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5391; }","duration":"845.677248ms","start":"2026-09-19T01:08:29.858918Z","end":"2026-09-19T01:08:30.704595Z","steps":["trace[105616244] 'agreement among raft nodes before linearized reading' (duration: 845.524746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:30.704646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:08:29.858902Z","time spent":"845.733531ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-19T01:08:30.966181Z","caller":"traceutil/trace.go:171","msg":"trace[359123569] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"251.104213ms","start":"2026-09-19T01:08:30.715014Z","end":"2026-09-19T01:08:30.966118Z","steps":["trace[359123569] 'process raft request' (duration: 250.823799ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:30.966229Z","caller":"traceutil/trace.go:171","msg":"trace[734396158] linearizableReadLoop","detail":"{readStateIndex:5606; appliedIndex:5600; }","duration":"181.592116ms","start":"2026-09-19T01:08:30.78462Z","end":"2026-09-19T01:08:30.966212Z","steps":["trace[734396158] 'read index received' (duration: 5.912538ms)","trace[734396158] 'applied index is now lower than readState.Index' (duration: 175.678498ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:30.966508Z","caller":"traceutil/trace.go:171","msg":"trace[618222761] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"249.431159ms","start":"2026-09-19T01:08:30.717059Z","end":"2026-09-19T01:08:30.96649Z","steps":["trace[618222761] 'process raft request' (duration: 249.106193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:30.966796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.148875ms","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-09-19T01:08:30.966888Z","caller":"traceutil/trace.go:171","msg":"trace[974842299] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5397; }","duration":"182.292567ms","start":"2026-09-19T01:08:30.784573Z","end":"2026-09-19T01:08:30.966866Z","steps":["trace[974842299] 'agreement among raft nodes before linearized reading' (duration: 182.145275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:30.96691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.855138ms","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-09-19T01:08:30.966965Z","caller":"traceutil/trace.go:171","msg":"trace[1669784890] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"251.820644ms","start":"2026-09-19T01:08:30.715086Z","end":"2026-09-19T01:08:30.966907Z","steps":["trace[1669784890] 'process raft request' (duration: 250.91074ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:30.968333Z","caller":"traceutil/trace.go:171","msg":"trace[2015150797] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"251.017061ms","start":"2026-09-19T01:08:30.715394Z","end":"2026-09-19T01:08:30.966411Z","steps":["trace[2015150797] 'process raft request' (duration: 250.726247ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:30.96843Z","caller":"traceutil/trace.go:171","msg":"trace[828752288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5397; }","duration":"164.949959ms","start":"2026-09-19T01:08:30.80201Z","end":"2026-09-19T01:08:30.96696Z","steps":["trace[828752288] 'agreement among raft nodes before linearized reading' (duration: 164.676155ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:30.96848Z","caller":"traceutil/trace.go:171","msg":"trace[326564561] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"251.952915ms","start":"2026-09-19T01:08:30.715249Z","end":"2026-09-19T01:08:30.967202Z","steps":["trace[326564561] 'process raft request' (duration: 250.813668ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:31.156067Z","caller":"traceutil/trace.go:171","msg":"trace[733647710] linearizableReadLoop","detail":"{readStateIndex:5608; appliedIndex:5607; }","duration":"150.280557ms","start":"2026-09-19T01:08:31.005751Z","end":"2026-09-19T01:08:31.156032Z","steps":["trace[733647710] 'read index received' (duration: 112.695468ms)","trace[733647710] 'applied index is now lower than readState.Index' (duration: 37.582839ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:31.156137Z","caller":"traceutil/trace.go:171","msg":"trace[193287622] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"152.572701ms","start":"2026-09-19T01:08:31.0035Z","end":"2026-09-19T01:08:31.156072Z","steps":["trace[193287622] 'process raft request' (duration: 114.987401ms)","trace[193287622] 'compare' (duration: 37.201705ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:08:31.156441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.660633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-09-19T01:08:31.156525Z","caller":"traceutil/trace.go:171","msg":"trace[4048682] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5399; }","duration":"150.791694ms","start":"2026-09-19T01:08:31.005715Z","end":"2026-09-19T01:08:31.156507Z","steps":["trace[4048682] 'agreement among raft nodes before linearized reading' (duration: 150.4845ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:31.216655Z","caller":"traceutil/trace.go:171","msg":"trace[333506380] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"191.805736ms","start":"2026-09-19T01:08:31.024812Z","end":"2026-09-19T01:08:31.216618Z","steps":["trace[333506380] 'process raft request' (duration: 191.648864ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:31.217818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.698953ms","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-09-19T01:08:31.217881Z","caller":"traceutil/trace.go:171","msg":"trace[469196514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5400; }","duration":"121.851564ms","start":"2026-09-19T01:08:31.096015Z","end":"2026-09-19T01:08:31.217866Z","steps":["trace[469196514] 'agreement among raft nodes before linearized reading' (duration: 121.59209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:31.217885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.367845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T01:08:31.217975Z","caller":"traceutil/trace.go:171","msg":"trace[1193398312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5400; }","duration":"143.512706ms","start":"2026-09-19T01:08:31.07444Z","end":"2026-09-19T01:08:31.217953Z","steps":["trace[1193398312] 'agreement among raft nodes before linearized reading' (duration: 143.235302ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:31.373269Z","caller":"traceutil/trace.go:171","msg":"trace[835787636] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"152.073365ms","start":"2026-09-19T01:08:31.221144Z","end":"2026-09-19T01:08:31.373217Z","steps":["trace[835787636] 'process raft request' (duration: 86.721381ms)","trace[835787636] 'compare' (duration: 65.228482ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:31.409763Z","caller":"traceutil/trace.go:171","msg":"trace[1424081423] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"186.973704ms","start":"2026-09-19T01:08:31.222772Z","end":"2026-09-19T01:08:31.409745Z","steps":["trace[1424081423] 'process raft request' (duration: 186.860782ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:31.409766Z","caller":"traceutil/trace.go:171","msg":"trace[964575573] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"187.049175ms","start":"2026-09-19T01:08:31.222678Z","end":"2026-09-19T01:08:31.409728Z","steps":["trace[964575573] 'process raft request' (duration: 186.816742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:31.410054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.670771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-09-19T01:08:31.410108Z","caller":"traceutil/trace.go:171","msg":"trace[795950876] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5403; }","duration":"178.738262ms","start":"2026-09-19T01:08:31.231355Z","end":"2026-09-19T01:08:31.410093Z","steps":["trace[795950876] 'agreement among raft nodes before linearized reading' (duration: 178.447227ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:31.409701Z","caller":"traceutil/trace.go:171","msg":"trace[1983224495] linearizableReadLoop","detail":"{readStateIndex:5612; appliedIndex:5609; }","duration":"178.303835ms","start":"2026-09-19T01:08:31.231371Z","end":"2026-09-19T01:08:31.409675Z","steps":["trace[1983224495] 'read index received' (duration: 76.479178ms)","trace[1983224495] 'applied index is now lower than readState.Index' (duration: 101.823367ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:09:07.281541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.977813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:09:07.281661Z","caller":"traceutil/trace.go:171","msg":"trace[890840008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5702; }","duration":"464.134916ms","start":"2026-09-19T01:09:06.817502Z","end":"2026-09-19T01:09:07.281637Z","steps":["trace[890840008] 'range keys from in-memory index tree' (duration: 463.924192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:09:07.281705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:09:06.817489Z","time spent":"464.206347ms","remote":"127.0.0.1:58796","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-09-19T01:09:07.281877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.010989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-19T01:09:07.281938Z","caller":"traceutil/trace.go:171","msg":"trace[401283596] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5702; }","duration":"578.124461ms","start":"2026-09-19T01:09:06.703796Z","end":"2026-09-19T01:09:07.281921Z","steps":["trace[401283596] 'range keys from in-memory index tree' (duration: 577.840397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:09:07.282013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:09:06.703778Z","time spent":"578.188872ms","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-19T01:09:07.282191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.420645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:09:07.282279Z","caller":"traceutil/trace.go:171","msg":"trace[723399082] transaction","detail":"{read_only:false; response_revision:5703; number_of_response:1; }","duration":"435.038846ms","start":"2026-09-19T01:09:06.847223Z","end":"2026-09-19T01:09:07.282261Z","steps":["trace[723399082] 'process raft request' (duration: 206.447889ms)","trace[723399082] 'compare' (duration: 227.890457ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:09:07.282346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:09:06.847194Z","time spent":"435.117008ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:09:07.307562Z","caller":"traceutil/trace.go:171","msg":"trace[1709756896] linearizableReadLoop","detail":"{readStateIndex:5921; appliedIndex:5919; }","duration":"333.353857ms","start":"2026-09-19T01:09:06.974183Z","end":"2026-09-19T01:09:07.307537Z","steps":["trace[1709756896] 'read index received' (duration: 79.457176ms)","trace[1709756896] 'applied index is now lower than readState.Index' (duration: 253.895161ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:09:07.307816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.611351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:09:07.307867Z","caller":"traceutil/trace.go:171","msg":"trace[425850167] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5704; }","duration":"333.727102ms","start":"2026-09-19T01:09:06.974125Z","end":"2026-09-19T01:09:07.307852Z","steps":["trace[425850167] 'agreement among raft nodes before linearized reading' (duration: 333.59394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:09:07.307898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:09:06.974102Z","time spent":"333.786944ms","remote":"127.0.0.1:35844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-09-19T01:09:07.308295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.111667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-19T01:09:07.308398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.966851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-19T01:09:07.308443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.155754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"info","ts":"2026-09-19T01:09:07.308469Z","caller":"traceutil/trace.go:171","msg":"trace[1428640368] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:5704; }","duration":"258.055672ms","start":"2026-09-19T01:09:07.050388Z","end":"2026-09-19T01:09:07.308444Z","steps":["trace[1428640368] 'agreement among raft nodes before linearized reading' (duration: 257.956051ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:07.308485Z","caller":"traceutil/trace.go:171","msg":"trace[1509585049] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5704; }","duration":"274.211455ms","start":"2026-09-19T01:09:07.03426Z","end":"2026-09-19T01:09:07.308472Z","steps":["trace[1509585049] 'agreement among raft nodes before linearized reading' (duration: 274.135264ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:07.308554Z","caller":"traceutil/trace.go:171","msg":"trace[1724325003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5704; }","duration":"155.36188ms","start":"2026-09-19T01:09:07.153064Z","end":"2026-09-19T01:09:07.308425Z","steps":["trace[1724325003] 'agreement among raft nodes before linearized reading' (duration: 154.954404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:09:07.308651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.3417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:09:07.308288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.22544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-19T01:09:07.308734Z","caller":"traceutil/trace.go:171","msg":"trace[1392223169] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5704; }","duration":"277.464751ms","start":"2026-09-19T01:09:07.03125Z","end":"2026-09-19T01:09:07.308714Z","steps":["trace[1392223169] 'agreement among raft nodes before linearized reading' (duration: 277.338999ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:07.308738Z","caller":"traceutil/trace.go:171","msg":"trace[1192407511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5704; }","duration":"327.797707ms","start":"2026-09-19T01:09:06.98093Z","end":"2026-09-19T01:09:07.308728Z","steps":["trace[1192407511] 'agreement among raft nodes before linearized reading' (duration: 326.803383ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:07.307685Z","caller":"traceutil/trace.go:171","msg":"trace[754523441] transaction","detail":"{read_only:false; response_revision:5704; number_of_response:1; }","duration":"459.754402ms","start":"2026-09-19T01:09:06.847917Z","end":"2026-09-19T01:09:07.307672Z","steps":["trace[754523441] 'process raft request' (duration: 459.486488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:09:07.308828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:09:06.980913Z","time spent":"327.896508ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-19T01:09:07.308382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.067455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:09:07.308954Z","caller":"traceutil/trace.go:171","msg":"trace[2146139050] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5704; }","duration":"277.639933ms","start":"2026-09-19T01:09:07.031306Z","end":"2026-09-19T01:09:07.308946Z","steps":["trace[2146139050] 'agreement among raft nodes before linearized reading' (duration: 277.054955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:09:07.308328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.610434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-19T01:09:07.309057Z","caller":"traceutil/trace.go:171","msg":"trace[1630714973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5704; }","duration":"131.384945ms","start":"2026-09-19T01:09:07.177659Z","end":"2026-09-19T01:09:07.309044Z","steps":["trace[1630714973] 'agreement among raft nodes before linearized reading' (duration: 130.572424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:09:07.310532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:09:06.847899Z","time spent":"460.913798ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:09:07.542595Z","caller":"traceutil/trace.go:171","msg":"trace[1733582755] transaction","detail":"{read_only:false; response_revision:5705; number_of_response:1; }","duration":"228.208782ms","start":"2026-09-19T01:09:07.314349Z","end":"2026-09-19T01:09:07.542558Z","steps":["trace[1733582755] 'process raft request' (duration: 228.036789ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:07.544088Z","caller":"traceutil/trace.go:171","msg":"trace[1829287395] linearizableReadLoop","detail":"{readStateIndex:5925; appliedIndex:5921; }","duration":"171.21125ms","start":"2026-09-19T01:09:07.372857Z","end":"2026-09-19T01:09:07.544068Z","steps":["trace[1829287395] 'read index received' (duration: 169.450024ms)","trace[1829287395] 'applied index is now lower than readState.Index' (duration: 1.760056ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:09:07.544137Z","caller":"traceutil/trace.go:171","msg":"trace[613543965] transaction","detail":"{read_only:false; response_revision:5707; number_of_response:1; }","duration":"227.611923ms","start":"2026-09-19T01:09:07.316501Z","end":"2026-09-19T01:09:07.544113Z","steps":["trace[613543965] 'process raft request' (duration: 227.432601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:09:07.544222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.349991ms","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-09-19T01:09:07.544276Z","caller":"traceutil/trace.go:171","msg":"trace[942826228] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5708; }","duration":"171.460242ms","start":"2026-09-19T01:09:07.372801Z","end":"2026-09-19T01:09:07.544261Z","steps":["trace[942826228] 'agreement among raft nodes before linearized reading' (duration: 171.341221ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:07.544324Z","caller":"traceutil/trace.go:171","msg":"trace[822410965] transaction","detail":"{read_only:false; response_revision:5706; number_of_response:1; }","duration":"229.687563ms","start":"2026-09-19T01:09:07.314619Z","end":"2026-09-19T01:09:07.544275Z","steps":["trace[822410965] 'process raft request' (duration: 229.207476ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:07.544658Z","caller":"traceutil/trace.go:171","msg":"trace[1114766171] transaction","detail":"{read_only:false; response_revision:5708; number_of_response:1; }","duration":"195.430828ms","start":"2026-09-19T01:09:07.34921Z","end":"2026-09-19T01:09:07.544641Z","steps":["trace[1114766171] 'process raft request' (duration: 194.80983ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:09.68557Z","caller":"traceutil/trace.go:171","msg":"trace[1946717353] linearizableReadLoop","detail":"{readStateIndex:5931; appliedIndex:5930; }","duration":"370.17162ms","start":"2026-09-19T01:09:09.31538Z","end":"2026-09-19T01:09:09.685552Z","steps":["trace[1946717353] 'read index received' (duration: 282.793553ms)","trace[1946717353] 'applied index is now lower than readState.Index' (duration: 87.377117ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:09:09.685693Z","caller":"traceutil/trace.go:171","msg":"trace[369952544] transaction","detail":"{read_only:false; response_revision:5713; number_of_response:1; }","duration":"398.768473ms","start":"2026-09-19T01:09:09.286892Z","end":"2026-09-19T01:09:09.68566Z","steps":["trace[369952544] 'process raft request' (duration: 311.268532ms)","trace[369952544] 'compare' (duration: 87.042823ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:09:09.685821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.425035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"warn","ts":"2026-09-19T01:09:09.685843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:09:09.286876Z","time spent":"398.883524ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:09:09.685861Z","caller":"traceutil/trace.go:171","msg":"trace[726583559] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5714; }","duration":"370.510015ms","start":"2026-09-19T01:09:09.31534Z","end":"2026-09-19T01:09:09.68585Z","steps":["trace[726583559] 'agreement among raft nodes before linearized reading' (duration: 370.316853ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:09.685846Z","caller":"traceutil/trace.go:171","msg":"trace[1320118067] transaction","detail":"{read_only:false; response_revision:5714; number_of_response:1; }","duration":"369.133496ms","start":"2026-09-19T01:09:09.316689Z","end":"2026-09-19T01:09:09.685822Z","steps":["trace[1320118067] 'process raft request' (duration: 368.822301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:09:09.68589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:09:09.315319Z","time spent":"370.564297ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6180,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-09-19T01:09:09.686012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:09:09.316674Z","time spent":"369.235107ms","remote":"127.0.0.1:59046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:09:09.686064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.657678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:09:09.686119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.38975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-19T01:09:09.686161Z","caller":"traceutil/trace.go:171","msg":"trace[678626860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5715; }","duration":"135.45242ms","start":"2026-09-19T01:09:09.550698Z","end":"2026-09-19T01:09:09.686151Z","steps":["trace[678626860] 'agreement among raft nodes before linearized reading' (duration: 135.270288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:09:09.686167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.740009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:09:09.6862Z","caller":"traceutil/trace.go:171","msg":"trace[1170465752] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5715; }","duration":"370.788249ms","start":"2026-09-19T01:09:09.315404Z","end":"2026-09-19T01:09:09.686192Z","steps":["trace[1170465752] 'agreement among raft nodes before linearized reading' (duration: 370.740428ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:09:09.686202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.345727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-19T01:09:09.686233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:09:09.315399Z","time spent":"370.828041ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"info","ts":"2026-09-19T01:09:09.686269Z","caller":"traceutil/trace.go:171","msg":"trace[2133659290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5715; }","duration":"139.454999ms","start":"2026-09-19T01:09:09.546797Z","end":"2026-09-19T01:09:09.686252Z","steps":["trace[2133659290] 'agreement among raft nodes before linearized reading' (duration: 139.265696ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:09.686122Z","caller":"traceutil/trace.go:171","msg":"trace[1336696869] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5715; }","duration":"370.754409ms","start":"2026-09-19T01:09:09.315355Z","end":"2026-09-19T01:09:09.68611Z","steps":["trace[1336696869] 'agreement among raft nodes before linearized reading' (duration: 370.587017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:09:09.68631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.230936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-19T01:09:09.686348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:09:09.315337Z","time spent":"371.003012ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"info","ts":"2026-09-19T01:09:09.686355Z","caller":"traceutil/trace.go:171","msg":"trace[1760301796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5715; }","duration":"137.315817ms","start":"2026-09-19T01:09:09.549027Z","end":"2026-09-19T01:09:09.686343Z","steps":["trace[1760301796] 'agreement among raft nodes before linearized reading' (duration: 137.212246ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:09.686389Z","caller":"traceutil/trace.go:171","msg":"trace[1793118494] transaction","detail":"{read_only:false; response_revision:5715; number_of_response:1; }","duration":"134.529116ms","start":"2026-09-19T01:09:09.551843Z","end":"2026-09-19T01:09:09.686372Z","steps":["trace[1793118494] 'process raft request' (duration: 133.856907ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:09.881506Z","caller":"traceutil/trace.go:171","msg":"trace[1978761551] transaction","detail":"{read_only:false; response_revision:5716; number_of_response:1; }","duration":"190.638275ms","start":"2026-09-19T01:09:09.690834Z","end":"2026-09-19T01:09:09.881472Z","steps":["trace[1978761551] 'process raft request' (duration: 136.088429ms)","trace[1978761551] 'compare' (duration: 54.397974ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:09:09.901013Z","caller":"traceutil/trace.go:171","msg":"trace[182380625] transaction","detail":"{read_only:false; response_revision:5717; number_of_response:1; }","duration":"210.141786ms","start":"2026-09-19T01:09:09.690854Z","end":"2026-09-19T01:09:09.900996Z","steps":["trace[182380625] 'process raft request' (duration: 209.998854ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:09.901485Z","caller":"traceutil/trace.go:171","msg":"trace[541908714] transaction","detail":"{read_only:false; response_revision:5718; number_of_response:1; }","duration":"210.41371ms","start":"2026-09-19T01:09:09.691056Z","end":"2026-09-19T01:09:09.90147Z","steps":["trace[541908714] 'process raft request' (duration: 209.882022ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:09.90158Z","caller":"traceutil/trace.go:171","msg":"trace[2093202279] transaction","detail":"{read_only:false; response_revision:5719; number_of_response:1; }","duration":"210.241188ms","start":"2026-09-19T01:09:09.691312Z","end":"2026-09-19T01:09:09.901553Z","steps":["trace[2093202279] 'process raft request' (duration: 210.021435ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:09:09.901743Z","caller":"traceutil/trace.go:171","msg":"trace[722800385] transaction","detail":"{read_only:false; response_revision:5720; number_of_response:1; }","duration":"209.72644ms","start":"2026-09-19T01:09:09.691996Z","end":"2026-09-19T01:09:09.901722Z","steps":["trace[722800385] 'process raft request' (duration: 209.447056ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:13:15.719132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5223} {"level":"info","ts":"2026-09-19T01:13:15.804706Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5223,"took":"83.964318ms","hash":2253231091,"current-db-size-bytes":52322304,"current-db-size":"52 MB","current-db-size-in-use-bytes":19546112,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-19T01:13:15.804775Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2253231091,"revision":5223,"compact-revision":2859}