{"level":"warn","ts":"2026-03-20T10:15:05.696946Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T10:15:05.697048Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.162:2380","--initial-cluster=instance=https://199.19.213.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.162: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-03-20T10:15:05.697143Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T10:15:05.697155Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-03-20T10:15:05.697175Z","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-03-20T10:15:05.69787Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"]} {"level":"info","ts":"2026-03-20T10:15:05.697951Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.162: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-03-20T10:15:05.701667Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.431062ms"} {"level":"info","ts":"2026-03-20T10:15:05.709432Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8c0a14645b8502c5","cluster-id":"49fe27222e1c6db4"} {"level":"info","ts":"2026-03-20T10:15:05.709561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-20T10:15:05.709597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 0"} {"level":"info","ts":"2026-03-20T10:15:05.709621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8c0a14645b8502c5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-20T10:15:05.709635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 1"} {"level":"info","ts":"2026-03-20T10:15:05.709685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"warn","ts":"2026-03-20T10:15:05.716255Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-20T10:15:05.717845Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-20T10:15:05.719035Z","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-03-20T10:15:05.722143Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8c0a14645b8502c5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-20T10:15:05.722403Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8c0a14645b8502c5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-20T10:15:05.722588Z","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-03-20T10:15:05.722747Z","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-03-20T10:15:05.722792Z","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-03-20T10:15:05.723304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"info","ts":"2026-03-20T10:15:05.724057Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","added-peer-id":"8c0a14645b8502c5","added-peer-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-03-20T10:15:05.729066Z","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-03-20T10:15:05.729222Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-03-20T10:15:05.729302Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-03-20T10:15:05.729468Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8c0a14645b8502c5","initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-20T10:15:05.729532Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-20T10:15:06.609943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-20T10:15:06.610137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-20T10:15:06.610184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgPreVoteResp from 8c0a14645b8502c5 at term 1"} {"level":"info","ts":"2026-03-20T10:15:06.610215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became candidate at term 2"} {"level":"info","ts":"2026-03-20T10:15:06.610236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgVoteResp from 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-03-20T10:15:06.610277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became leader at term 2"} {"level":"info","ts":"2026-03-20T10:15:06.610499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8c0a14645b8502c5 elected leader 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-03-20T10:15:06.611513Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8c0a14645b8502c5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.162:2379]}","request-path":"/0/members/8c0a14645b8502c5/attributes","cluster-id":"49fe27222e1c6db4","publish-timeout":"7s"} {"level":"info","ts":"2026-03-20T10:15:06.611583Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T10:15:06.611647Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T10:15:06.611664Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:15:06.612025Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-20T10:15:06.612055Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-20T10:15:06.614008Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:15:06.614293Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:15:06.61446Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:15:06.615277Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.162:2379"} {"level":"info","ts":"2026-03-20T10:15:06.615662Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-20T10:15:26.116536Z","caller":"traceutil/trace.go:171","msg":"trace[740394742] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"145.914177ms","start":"2026-03-20T10:15:25.970579Z","end":"2026-03-20T10:15:26.116493Z","steps":["trace[740394742] 'process raft request' (duration: 145.656501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:15:26.378318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.262233ms","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-03-20T10:15:26.37847Z","caller":"traceutil/trace.go:171","msg":"trace[853168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:448; }","duration":"138.489529ms","start":"2026-03-20T10:15:26.239955Z","end":"2026-03-20T10:15:26.378444Z","steps":["trace[853168] 'range keys from in-memory index tree' (duration: 138.13283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:15:26.378933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.665932ms","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-03-20T10:15:26.379003Z","caller":"traceutil/trace.go:171","msg":"trace[862104527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:448; }","duration":"195.808946ms","start":"2026-03-20T10:15:26.183182Z","end":"2026-03-20T10:15:26.378991Z","steps":["trace[862104527] 'range keys from in-memory index tree' (duration: 195.529589ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:15:26.487444Z","caller":"traceutil/trace.go:171","msg":"trace[516305172] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"104.696907ms","start":"2026-03-20T10:15:26.382721Z","end":"2026-03-20T10:15:26.487418Z","steps":["trace[516305172] 'process raft request' (duration: 104.530222ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:15:26.613296Z","caller":"traceutil/trace.go:171","msg":"trace[154063779] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"229.951526ms","start":"2026-03-20T10:15:26.383318Z","end":"2026-03-20T10:15:26.613269Z","steps":["trace[154063779] 'process raft request' (duration: 221.705989ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:15:38.867936Z","caller":"traceutil/trace.go:171","msg":"trace[367196512] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"172.473105ms","start":"2026-03-20T10:15:38.69544Z","end":"2026-03-20T10:15:38.867913Z","steps":["trace[367196512] 'process raft request' (duration: 172.338982ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:15:45.897697Z","caller":"traceutil/trace.go:171","msg":"trace[118845586] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"200.904057ms","start":"2026-03-20T10:15:45.696765Z","end":"2026-03-20T10:15:45.897669Z","steps":["trace[118845586] 'read index received' (duration: 200.61472ms)","trace[118845586] 'applied index is now lower than readState.Index' (duration: 287.957µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:15:45.897925Z","caller":"traceutil/trace.go:171","msg":"trace[505502228] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"221.78663ms","start":"2026-03-20T10:15:45.676101Z","end":"2026-03-20T10:15:45.897887Z","steps":["trace[505502228] 'process raft request' (duration: 221.374921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:15:45.89805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.260966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6lgp8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-20T10:15:45.898112Z","caller":"traceutil/trace.go:171","msg":"trace[759780615] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6lgp8; range_end:; response_count:1; response_revision:553; }","duration":"201.383929ms","start":"2026-03-20T10:15:45.696711Z","end":"2026-03-20T10:15:45.898095Z","steps":["trace[759780615] 'agreement among raft nodes before linearized reading' (duration: 201.074852ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:15:46.144645Z","caller":"traceutil/trace.go:171","msg":"trace[24182451] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"232.397244ms","start":"2026-03-20T10:15:45.91221Z","end":"2026-03-20T10:15:46.144607Z","steps":["trace[24182451] 'process raft request' (duration: 232.107337ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:16:05.231401Z","caller":"traceutil/trace.go:171","msg":"trace[330878483] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"164.543404ms","start":"2026-03-20T10:16:05.066836Z","end":"2026-03-20T10:16:05.23138Z","steps":["trace[330878483] 'process raft request' (duration: 164.419441ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:00.398135Z","caller":"traceutil/trace.go:171","msg":"trace[1943977728] transaction","detail":"{read_only:false; response_revision:1618; number_of_response:1; }","duration":"173.307778ms","start":"2026-03-20T10:17:00.224804Z","end":"2026-03-20T10:17:00.398112Z","steps":["trace[1943977728] 'process raft request' (duration: 173.155834ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:00.398276Z","caller":"traceutil/trace.go:171","msg":"trace[896005611] linearizableReadLoop","detail":"{readStateIndex:1686; appliedIndex:1686; }","duration":"147.733418ms","start":"2026-03-20T10:17:00.250518Z","end":"2026-03-20T10:17:00.398251Z","steps":["trace[896005611] 'read index received' (duration: 147.722098ms)","trace[896005611] 'applied index is now lower than readState.Index' (duration: 9.05µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:17:00.398421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.868161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T10:17:00.398451Z","caller":"traceutil/trace.go:171","msg":"trace[207385855] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1618; }","duration":"147.975954ms","start":"2026-03-20T10:17:00.250467Z","end":"2026-03-20T10:17:00.398443Z","steps":["trace[207385855] 'agreement among raft nodes before linearized reading' (duration: 147.881582ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:00.399682Z","caller":"traceutil/trace.go:171","msg":"trace[1357524935] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"123.286306ms","start":"2026-03-20T10:17:00.27634Z","end":"2026-03-20T10:17:00.399626Z","steps":["trace[1357524935] 'process raft request' (duration: 122.886196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:17:05.302322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.24509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T10:17:05.302496Z","caller":"traceutil/trace.go:171","msg":"trace[829903013] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1668; }","duration":"122.534156ms","start":"2026-03-20T10:17:05.179937Z","end":"2026-03-20T10:17:05.302471Z","steps":["trace[829903013] 'count revisions from in-memory index tree' (duration: 122.094446ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:12.612357Z","caller":"traceutil/trace.go:171","msg":"trace[1831950865] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"110.696516ms","start":"2026-03-20T10:17:12.501631Z","end":"2026-03-20T10:17:12.612327Z","steps":["trace[1831950865] 'process raft request' (duration: 110.518852ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:12.633329Z","caller":"traceutil/trace.go:171","msg":"trace[2125727076] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"121.655075ms","start":"2026-03-20T10:17:12.511639Z","end":"2026-03-20T10:17:12.633294Z","steps":["trace[2125727076] 'process raft request' (duration: 121.46587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:17:55.497911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.558514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:17:55.498187Z","caller":"traceutil/trace.go:171","msg":"trace[1392917033] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"373.672905ms","start":"2026-03-20T10:17:55.124441Z","end":"2026-03-20T10:17:55.498113Z","steps":["trace[1392917033] 'process raft request' (duration: 139.983303ms)","trace[1392917033] 'compare' (duration: 232.135124ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:17:55.498446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:17:55.124409Z","time spent":"373.887051ms","remote":"127.0.0.1:40338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:17:55.498391Z","caller":"traceutil/trace.go:171","msg":"trace[1447427632] linearizableReadLoop","detail":"{readStateIndex:2021; appliedIndex:2020; }","duration":"366.502549ms","start":"2026-03-20T10:17:55.131806Z","end":"2026-03-20T10:17:55.498309Z","steps":["trace[1447427632] 'read index received' (duration: 132.56118ms)","trace[1447427632] 'applied index is now lower than readState.Index' (duration: 233.906239ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:17:55.498657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.807138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.189e8547720e22b9\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-03-20T10:17:55.498739Z","caller":"traceutil/trace.go:171","msg":"trace[1447074204] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.189e8547720e22b9; range_end:; response_count:1; response_revision:1941; }","duration":"366.94594ms","start":"2026-03-20T10:17:55.131772Z","end":"2026-03-20T10:17:55.498718Z","steps":["trace[1447074204] 'agreement among raft nodes before linearized reading' (duration: 366.626313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:17:55.498795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:17:55.131758Z","time spent":"367.027093ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.189e8547720e22b9\" "} {"level":"warn","ts":"2026-03-20T10:17:55.498948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.939219ms","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-03-20T10:17:55.499011Z","caller":"traceutil/trace.go:171","msg":"trace[2110482237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1942; }","duration":"128.041461ms","start":"2026-03-20T10:17:55.370953Z","end":"2026-03-20T10:17:55.498995Z","steps":["trace[2110482237] 'agreement among raft nodes before linearized reading' (duration: 127.827986ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:55.49854Z","caller":"traceutil/trace.go:171","msg":"trace[160313330] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"154.456969ms","start":"2026-03-20T10:17:55.344051Z","end":"2026-03-20T10:17:55.498508Z","steps":["trace[160313330] 'process raft request' (duration: 154.074429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:17:55.499322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.377925ms","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-03-20T10:17:55.499442Z","caller":"traceutil/trace.go:171","msg":"trace[1611858074] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1942; }","duration":"116.58653ms","start":"2026-03-20T10:17:55.382828Z","end":"2026-03-20T10:17:55.499415Z","steps":["trace[1611858074] 'agreement among raft nodes before linearized reading' (duration: 116.141809ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:55.750987Z","caller":"traceutil/trace.go:171","msg":"trace[598962744] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"246.62748ms","start":"2026-03-20T10:17:55.504313Z","end":"2026-03-20T10:17:55.75094Z","steps":["trace[598962744] 'process raft request' (duration: 175.919595ms)","trace[598962744] 'compare' (duration: 70.537931ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:18:38.385276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.190416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:18:38.385443Z","caller":"traceutil/trace.go:171","msg":"trace[432562200] linearizableReadLoop","detail":"{readStateIndex:2424; appliedIndex:2423; }","duration":"273.63674ms","start":"2026-03-20T10:18:38.111787Z","end":"2026-03-20T10:18:38.385424Z","steps":["trace[432562200] 'read index received' (duration: 132.162057ms)","trace[432562200] 'applied index is now lower than readState.Index' (duration: 141.472792ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:18:38.385529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.741972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:18:38.385567Z","caller":"traceutil/trace.go:171","msg":"trace[130025083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2327; }","duration":"273.821804ms","start":"2026-03-20T10:18:38.111734Z","end":"2026-03-20T10:18:38.385556Z","steps":["trace[130025083] 'agreement among raft nodes before linearized reading' (duration: 273.740032ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:18:38.385551Z","caller":"traceutil/trace.go:171","msg":"trace[1119015557] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"316.006614ms","start":"2026-03-20T10:18:38.069496Z","end":"2026-03-20T10:18:38.385503Z","steps":["trace[1119015557] 'process raft request' (duration: 174.46671ms)","trace[1119015557] 'compare' (duration: 140.814857ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:18:38.386035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:18:38.069468Z","time spent":"316.434564ms","remote":"127.0.0.1:40338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:19:11.236019Z","caller":"traceutil/trace.go:171","msg":"trace[1922369417] transaction","detail":"{read_only:false; response_revision:2509; number_of_response:1; }","duration":"166.821394ms","start":"2026-03-20T10:19:11.069158Z","end":"2026-03-20T10:19:11.23598Z","steps":["trace[1922369417] 'process raft request' (duration: 166.609719ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:19:11.250012Z","caller":"traceutil/trace.go:171","msg":"trace[1705219788] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"113.771182ms","start":"2026-03-20T10:19:11.136195Z","end":"2026-03-20T10:19:11.249966Z","steps":["trace[1705219788] 'process raft request' (duration: 113.503435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:19:11.718152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.201164ms","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":"info","ts":"2026-03-20T10:19:11.718237Z","caller":"traceutil/trace.go:171","msg":"trace[734866546] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2511; }","duration":"236.336808ms","start":"2026-03-20T10:19:11.481881Z","end":"2026-03-20T10:19:11.718218Z","steps":["trace[734866546] 'count revisions from in-memory index tree' (duration: 236.111131ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:19:11.819373Z","caller":"traceutil/trace.go:171","msg":"trace[656621615] transaction","detail":"{read_only:false; response_revision:2512; number_of_response:1; }","duration":"313.443102ms","start":"2026-03-20T10:19:11.505904Z","end":"2026-03-20T10:19:11.819348Z","steps":["trace[656621615] 'process raft request' (duration: 313.303158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:19:11.819516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:19:11.505867Z","time spent":"313.579965ms","remote":"127.0.0.1:40338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:21:53.788436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.126598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-03-20T10:21:53.788614Z","caller":"traceutil/trace.go:171","msg":"trace[1799632745] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:3485; }","duration":"134.354093ms","start":"2026-03-20T10:21:53.654231Z","end":"2026-03-20T10:21:53.788585Z","steps":["trace[1799632745] 'range keys from in-memory index tree' (duration: 133.865301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:21:53.788505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.857019ms","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-03-20T10:21:53.788701Z","caller":"traceutil/trace.go:171","msg":"trace[1621297563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3485; }","duration":"103.134145ms","start":"2026-03-20T10:21:53.685539Z","end":"2026-03-20T10:21:53.788673Z","steps":["trace[1621297563] 'range keys from in-memory index tree' (duration: 102.598713ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:11.870112Z","caller":"traceutil/trace.go:171","msg":"trace[216426305] transaction","detail":"{read_only:false; response_revision:3943; number_of_response:1; }","duration":"183.819218ms","start":"2026-03-20T10:23:11.686067Z","end":"2026-03-20T10:23:11.869886Z","steps":["trace[216426305] 'process raft request' (duration: 183.244834ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:25.487745Z","caller":"traceutil/trace.go:171","msg":"trace[1847695980] transaction","detail":"{read_only:false; response_revision:4451; number_of_response:1; }","duration":"123.298213ms","start":"2026-03-20T10:23:25.36441Z","end":"2026-03-20T10:23:25.487708Z","steps":["trace[1847695980] 'process raft request' (duration: 30.301974ms)","trace[1847695980] 'compare' (duration: 92.770544ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:28.73697Z","caller":"traceutil/trace.go:171","msg":"trace[2035336239] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"192.826283ms","start":"2026-03-20T10:23:28.544109Z","end":"2026-03-20T10:23:28.736936Z","steps":["trace[2035336239] 'process raft request' (duration: 190.436306ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:28.736805Z","caller":"traceutil/trace.go:171","msg":"trace[58036967] linearizableReadLoop","detail":"{readStateIndex:4706; appliedIndex:4705; }","duration":"188.312334ms","start":"2026-03-20T10:23:28.548414Z","end":"2026-03-20T10:23:28.736726Z","steps":["trace[58036967] 'read index received' (duration: 186.199903ms)","trace[58036967] 'applied index is now lower than readState.Index' (duration: 2.110941ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:28.737204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.758605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:23:28.737281Z","caller":"traceutil/trace.go:171","msg":"trace[240409966] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4523; }","duration":"188.882498ms","start":"2026-03-20T10:23:28.548383Z","end":"2026-03-20T10:23:28.737265Z","steps":["trace[240409966] 'agreement among raft nodes before linearized reading' (duration: 188.693873ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:28.782178Z","caller":"traceutil/trace.go:171","msg":"trace[1867552429] transaction","detail":"{read_only:false; response_revision:4525; number_of_response:1; }","duration":"200.979797ms","start":"2026-03-20T10:23:28.581172Z","end":"2026-03-20T10:23:28.782152Z","steps":["trace[1867552429] 'process raft request' (duration: 200.889445ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:28.7822Z","caller":"traceutil/trace.go:171","msg":"trace[1000763206] transaction","detail":"{read_only:false; response_revision:4524; number_of_response:1; }","duration":"212.574484ms","start":"2026-03-20T10:23:28.569583Z","end":"2026-03-20T10:23:28.782157Z","steps":["trace[1000763206] 'process raft request' (duration: 212.313058ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:29.116051Z","caller":"traceutil/trace.go:171","msg":"trace[1872481154] transaction","detail":"{read_only:false; response_revision:4527; number_of_response:1; }","duration":"372.88997ms","start":"2026-03-20T10:23:28.74312Z","end":"2026-03-20T10:23:29.11601Z","steps":["trace[1872481154] 'process raft request' (duration: 372.786708ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:29.11611Z","caller":"traceutil/trace.go:171","msg":"trace[1008311401] transaction","detail":"{read_only:false; response_revision:4528; number_of_response:1; }","duration":"371.522797ms","start":"2026-03-20T10:23:28.744524Z","end":"2026-03-20T10:23:29.116047Z","steps":["trace[1008311401] 'process raft request' (duration: 371.414764ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:29.116138Z","caller":"traceutil/trace.go:171","msg":"trace[1163749755] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"373.654678ms","start":"2026-03-20T10:23:28.742434Z","end":"2026-03-20T10:23:29.116089Z","steps":["trace[1163749755] 'process raft request' (duration: 373.348311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.116217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:28.743093Z","time spent":"373.036804ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T10:23:29.11634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:28.744516Z","time spent":"371.700842ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":931,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T10:23:29.116361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:28.742409Z","time spent":"373.866213ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":928,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T10:23:29.116401Z","caller":"traceutil/trace.go:171","msg":"trace[60026633] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"371.420555ms","start":"2026-03-20T10:23:28.744918Z","end":"2026-03-20T10:23:29.116338Z","steps":["trace[60026633] 'process raft request' (duration: 371.059416ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:29.11648Z","caller":"traceutil/trace.go:171","msg":"trace[653330954] transaction","detail":"{read_only:false; response_revision:4531; number_of_response:1; }","duration":"371.416575ms","start":"2026-03-20T10:23:28.745015Z","end":"2026-03-20T10:23:29.116432Z","steps":["trace[653330954] 'process raft request' (duration: 370.980925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.116567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:28.744991Z","time spent":"371.536427ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":943,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T10:23:29.116577Z","caller":"traceutil/trace.go:171","msg":"trace[402416665] transaction","detail":"{read_only:false; response_revision:4532; number_of_response:1; }","duration":"362.491732ms","start":"2026-03-20T10:23:28.754067Z","end":"2026-03-20T10:23:29.116558Z","steps":["trace[402416665] 'process raft request' (duration: 361.951259ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:29.116588Z","caller":"traceutil/trace.go:171","msg":"trace[1025370163] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"371.821634ms","start":"2026-03-20T10:23:28.744748Z","end":"2026-03-20T10:23:29.11657Z","steps":["trace[1025370163] 'process raft request' (duration: 371.2108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.116619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:28.74489Z","time spent":"371.62724ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":943,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T10:23:29.116678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:28.744721Z","time spent":"371.917877ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":919,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T10:23:29.116717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:28.754045Z","time spent":"362.585085ms","remote":"127.0.0.1:56988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3952,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:23:29.116088Z","caller":"traceutil/trace.go:171","msg":"trace[273862424] linearizableReadLoop","detail":"{readStateIndex:4715; appliedIndex:4708; }","duration":"333.956831ms","start":"2026-03-20T10:23:28.782105Z","end":"2026-03-20T10:23:29.116062Z","steps":["trace[273862424] 'read index received' (duration: 333.732336ms)","trace[273862424] 'applied index is now lower than readState.Index' (duration: 222.975µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:29.116942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.924959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-03-20T10:23:29.116988Z","caller":"traceutil/trace.go:171","msg":"trace[310650655] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4532; }","duration":"374.99854ms","start":"2026-03-20T10:23:28.741978Z","end":"2026-03-20T10:23:29.116977Z","steps":["trace[310650655] 'agreement among raft nodes before linearized reading' (duration: 374.824626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.117021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:28.741963Z","time spent":"375.051233ms","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":731,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"warn","ts":"2026-03-20T10:23:29.117436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.982123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvwv6.189e859a1bf4e3c7\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-03-20T10:23:29.11758Z","caller":"traceutil/trace.go:171","msg":"trace[1107119455] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvwv6.189e859a1bf4e3c7; range_end:; response_count:1; response_revision:4532; }","duration":"365.196408ms","start":"2026-03-20T10:23:28.752356Z","end":"2026-03-20T10:23:29.117552Z","steps":["trace[1107119455] 'agreement among raft nodes before linearized reading' (duration: 364.124901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.117648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:28.752339Z","time spent":"365.297291ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":855,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvwv6.189e859a1bf4e3c7\" "} {"level":"warn","ts":"2026-03-20T10:23:29.11881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.771147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2196043"} {"level":"info","ts":"2026-03-20T10:23:29.118996Z","caller":"traceutil/trace.go:171","msg":"trace[921759243] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4532; }","duration":"360.020923ms","start":"2026-03-20T10:23:28.758942Z","end":"2026-03-20T10:23:29.118962Z","steps":["trace[921759243] 'agreement among raft nodes before linearized reading' (duration: 357.255137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.119147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:28.758921Z","time spent":"360.132786ms","remote":"127.0.0.1:40170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":50,"response size":2196066,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-03-20T10:23:29.169596Z","caller":"traceutil/trace.go:171","msg":"trace[785952055] transaction","detail":"{read_only:false; response_revision:4534; number_of_response:1; }","duration":"167.329493ms","start":"2026-03-20T10:23:29.002222Z","end":"2026-03-20T10:23:29.169551Z","steps":["trace[785952055] 'process raft request' (duration: 167.19524ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:29.16965Z","caller":"traceutil/trace.go:171","msg":"trace[2093892249] transaction","detail":"{read_only:false; response_revision:4533; number_of_response:1; }","duration":"382.973821ms","start":"2026-03-20T10:23:28.786635Z","end":"2026-03-20T10:23:29.169609Z","steps":["trace[2093892249] 'process raft request' (duration: 382.568551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.169848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:28.786613Z","time spent":"383.127685ms","remote":"127.0.0.1:40338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:23:29.576011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.491842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:23:29.576179Z","caller":"traceutil/trace.go:171","msg":"trace[1452472035] transaction","detail":"{read_only:false; response_revision:4541; number_of_response:1; }","duration":"304.14488ms","start":"2026-03-20T10:23:29.272005Z","end":"2026-03-20T10:23:29.576149Z","steps":["trace[1452472035] 'process raft request' (duration: 78.352661ms)","trace[1452472035] 'compare' (duration: 225.311167ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:29.576265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:29.271753Z","time spent":"304.475987ms","remote":"127.0.0.1:56972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2352,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:23:29.603245Z","caller":"traceutil/trace.go:171","msg":"trace[1853922948] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4541; }","duration":"329.751392ms","start":"2026-03-20T10:23:29.273445Z","end":"2026-03-20T10:23:29.603197Z","steps":["trace[1853922948] 'process raft request' (duration: 329.571697ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:29.603274Z","caller":"traceutil/trace.go:171","msg":"trace[895416649] linearizableReadLoop","detail":"{readStateIndex:4725; appliedIndex:4723; }","duration":"326.007512ms","start":"2026-03-20T10:23:29.277244Z","end":"2026-03-20T10:23:29.603252Z","steps":["trace[895416649] 'read index received' (duration: 73.057174ms)","trace[895416649] 'applied index is now lower than readState.Index' (duration: 252.949428ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:29.603358Z","caller":"traceutil/trace.go:171","msg":"trace[37270050] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"324.026025ms","start":"2026-03-20T10:23:29.279281Z","end":"2026-03-20T10:23:29.603307Z","steps":["trace[37270050] 'process raft request' (duration: 323.849691ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:29.603427Z","caller":"traceutil/trace.go:171","msg":"trace[2123087816] transaction","detail":"{read_only:false; response_revision:4543; number_of_response:1; }","duration":"264.886243ms","start":"2026-03-20T10:23:29.338481Z","end":"2026-03-20T10:23:29.603368Z","steps":["trace[2123087816] 'process raft request' (duration: 264.732969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.603514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:29.279261Z","time spent":"324.170748ms","remote":"127.0.0.1:36560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4834,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:23:29.603477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.219207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-03-20T10:23:29.603708Z","caller":"traceutil/trace.go:171","msg":"trace[1223057539] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4543; }","duration":"326.470843ms","start":"2026-03-20T10:23:29.277199Z","end":"2026-03-20T10:23:29.60367Z","steps":["trace[1223057539] 'agreement among raft nodes before linearized reading' (duration: 326.152085ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.60382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:29.277186Z","time spent":"326.596385ms","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1154,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-03-20T10:23:29.603891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:29.273409Z","time spent":"329.929536ms","remote":"127.0.0.1:56972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":110,"response count":0,"response size":2401,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:23:29.667015Z","caller":"traceutil/trace.go:171","msg":"trace[1287073462] transaction","detail":"{read_only:false; response_revision:4544; number_of_response:1; }","duration":"286.492768ms","start":"2026-03-20T10:23:29.380495Z","end":"2026-03-20T10:23:29.666987Z","steps":["trace[1287073462] 'process raft request' (duration: 286.256273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.667367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.091308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"warn","ts":"2026-03-20T10:23:29.667385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.821212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:23:29.667407Z","caller":"traceutil/trace.go:171","msg":"trace[2103054962] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4545; }","duration":"389.17953ms","start":"2026-03-20T10:23:29.278216Z","end":"2026-03-20T10:23:29.667395Z","steps":["trace[2103054962] 'agreement among raft nodes before linearized reading' (duration: 389.021256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.667381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.661796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-20T10:23:29.667447Z","caller":"traceutil/trace.go:171","msg":"trace[710552604] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:4545; }","duration":"179.892394ms","start":"2026-03-20T10:23:29.487539Z","end":"2026-03-20T10:23:29.667431Z","steps":["trace[710552604] 'agreement among raft nodes before linearized reading' (duration: 179.786671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.66745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:29.278206Z","time spent":"389.235051ms","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":719,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" "} {"level":"info","ts":"2026-03-20T10:23:29.667476Z","caller":"traceutil/trace.go:171","msg":"trace[2036896170] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:4545; }","duration":"106.815529ms","start":"2026-03-20T10:23:29.560638Z","end":"2026-03-20T10:23:29.667453Z","steps":["trace[2036896170] 'agreement among raft nodes before linearized reading' (duration: 106.559673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.667474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.860444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvwv6\" ","response":"range_response_count:1 size:9716"} {"level":"info","ts":"2026-03-20T10:23:29.667569Z","caller":"traceutil/trace.go:171","msg":"trace[1072489428] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvwv6; range_end:; response_count:1; response_revision:4545; }","duration":"330.023157ms","start":"2026-03-20T10:23:29.337523Z","end":"2026-03-20T10:23:29.667546Z","steps":["trace[1072489428] 'agreement among raft nodes before linearized reading' (duration: 329.72491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:29.667617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:29.337495Z","time spent":"330.11295ms","remote":"127.0.0.1:40238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9739,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvwv6\" "} {"level":"info","ts":"2026-03-20T10:23:30.760247Z","caller":"traceutil/trace.go:171","msg":"trace[306206972] transaction","detail":"{read_only:false; response_revision:4593; number_of_response:1; }","duration":"134.525621ms","start":"2026-03-20T10:23:30.625695Z","end":"2026-03-20T10:23:30.76022Z","steps":["trace[306206972] 'process raft request' (duration: 91.369451ms)","trace[306206972] 'compare' (duration: 43.010186ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:32.548902Z","caller":"traceutil/trace.go:171","msg":"trace[516609100] linearizableReadLoop","detail":"{readStateIndex:4857; appliedIndex:4856; }","duration":"119.055583ms","start":"2026-03-20T10:23:32.429826Z","end":"2026-03-20T10:23:32.548881Z","steps":["trace[516609100] 'read index received' (duration: 118.855428ms)","trace[516609100] 'applied index is now lower than readState.Index' (duration: 199.075µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:32.549005Z","caller":"traceutil/trace.go:171","msg":"trace[1760829043] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"164.848635ms","start":"2026-03-20T10:23:32.384115Z","end":"2026-03-20T10:23:32.548964Z","steps":["trace[1760829043] 'process raft request' (duration: 164.588919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:32.549292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.659466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T10:23:32.549407Z","caller":"traceutil/trace.go:171","msg":"trace[505952548] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4669; }","duration":"106.85104ms","start":"2026-03-20T10:23:32.442528Z","end":"2026-03-20T10:23:32.54938Z","steps":["trace[505952548] 'agreement among raft nodes before linearized reading' (duration: 106.653966ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:32.549427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.579224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719296"} {"level":"warn","ts":"2026-03-20T10:23:32.549435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.003813ms","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-03-20T10:23:32.549573Z","caller":"traceutil/trace.go:171","msg":"trace[1869488697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4669; }","duration":"112.183068ms","start":"2026-03-20T10:23:32.437353Z","end":"2026-03-20T10:23:32.549536Z","steps":["trace[1869488697] 'agreement among raft nodes before linearized reading' (duration: 111.794999ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:32.549733Z","caller":"traceutil/trace.go:171","msg":"trace[803341206] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4669; }","duration":"119.948113ms","start":"2026-03-20T10:23:32.429769Z","end":"2026-03-20T10:23:32.549718Z","steps":["trace[803341206] 'agreement among raft nodes before linearized reading' (duration: 119.251107ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:34.750573Z","caller":"traceutil/trace.go:171","msg":"trace[1696678670] linearizableReadLoop","detail":"{readStateIndex:4890; appliedIndex:4889; }","duration":"175.778155ms","start":"2026-03-20T10:23:34.574766Z","end":"2026-03-20T10:23:34.750544Z","steps":["trace[1696678670] 'read index received' (duration: 175.660502ms)","trace[1696678670] 'applied index is now lower than readState.Index' (duration: 116.653µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:34.750663Z","caller":"traceutil/trace.go:171","msg":"trace[2049125141] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"183.05132ms","start":"2026-03-20T10:23:34.567587Z","end":"2026-03-20T10:23:34.750638Z","steps":["trace[2049125141] 'process raft request' (duration: 182.719171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:34.750817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.037213ms","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-03-20T10:23:34.750843Z","caller":"traceutil/trace.go:171","msg":"trace[1775852137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4701; }","duration":"176.167215ms","start":"2026-03-20T10:23:34.57467Z","end":"2026-03-20T10:23:34.750837Z","steps":["trace[1775852137] 'agreement among raft nodes before linearized reading' (duration: 175.993391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:34.815472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.157467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-03-20T10:23:34.81562Z","caller":"traceutil/trace.go:171","msg":"trace[1070663851] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4702; }","duration":"223.382242ms","start":"2026-03-20T10:23:34.592201Z","end":"2026-03-20T10:23:34.815583Z","steps":["trace[1070663851] 'agreement among raft nodes before linearized reading' (duration: 201.307545ms)","trace[1070663851] 'range keys from in-memory index tree' (duration: 21.700738ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:35.124221Z","caller":"traceutil/trace.go:171","msg":"trace[2130634513] linearizableReadLoop","detail":"{readStateIndex:4899; appliedIndex:4898; }","duration":"140.171816ms","start":"2026-03-20T10:23:34.984023Z","end":"2026-03-20T10:23:35.124195Z","steps":["trace[2130634513] 'read index received' (duration: 59.173952ms)","trace[2130634513] 'applied index is now lower than readState.Index' (duration: 80.997054ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:35.12427Z","caller":"traceutil/trace.go:171","msg":"trace[447906305] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"232.443148ms","start":"2026-03-20T10:23:34.89178Z","end":"2026-03-20T10:23:35.124223Z","steps":["trace[447906305] 'process raft request' (duration: 151.538717ms)","trace[447906305] 'compare' (duration: 80.754358ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:35.124462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.401632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:23:35.124598Z","caller":"traceutil/trace.go:171","msg":"trace[1555710101] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4709; }","duration":"140.617137ms","start":"2026-03-20T10:23:34.983953Z","end":"2026-03-20T10:23:35.124571Z","steps":["trace[1555710101] 'agreement among raft nodes before linearized reading' (duration: 140.34692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:43.842268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.277311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:23:43.842456Z","caller":"traceutil/trace.go:171","msg":"trace[258273775] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"194.893963ms","start":"2026-03-20T10:23:43.64754Z","end":"2026-03-20T10:23:43.842434Z","steps":["trace[258273775] 'process raft request' (duration: 12.365106ms)","trace[258273775] 'compare' (duration: 182.070366ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:44.02722Z","caller":"traceutil/trace.go:171","msg":"trace[435335141] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"100.466658ms","start":"2026-03-20T10:23:43.926718Z","end":"2026-03-20T10:23:44.027184Z","steps":["trace[435335141] 'process raft request' (duration: 44.692496ms)","trace[435335141] 'compare' (duration: 55.570307ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:44.031548Z","caller":"traceutil/trace.go:171","msg":"trace[2146467665] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"104.067375ms","start":"2026-03-20T10:23:43.927459Z","end":"2026-03-20T10:23:44.031526Z","steps":["trace[2146467665] 'process raft request' (duration: 103.89817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:45.307428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.015829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T10:23:45.30753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.405288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:23:45.307564Z","caller":"traceutil/trace.go:171","msg":"trace[1044691478] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:4902; }","duration":"196.497871ms","start":"2026-03-20T10:23:45.111058Z","end":"2026-03-20T10:23:45.307556Z","steps":["trace[1044691478] 'range keys from in-memory index tree' (duration: 196.345947ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:45.307534Z","caller":"traceutil/trace.go:171","msg":"trace[1118105701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4902; }","duration":"196.150142ms","start":"2026-03-20T10:23:45.11136Z","end":"2026-03-20T10:23:45.30751Z","steps":["trace[1118105701] 'range keys from in-memory index tree' (duration: 195.968588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:45.535512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.209849ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-20T10:23:45.53591Z","caller":"traceutil/trace.go:171","msg":"trace[1579449866] linearizableReadLoop","detail":"{readStateIndex:5098; appliedIndex:5096; }","duration":"242.325075ms","start":"2026-03-20T10:23:45.293561Z","end":"2026-03-20T10:23:45.535886Z","steps":["trace[1579449866] 'read index received' (duration: 46.920701ms)","trace[1579449866] 'applied index is now lower than readState.Index' (duration: 195.403064ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:45.536068Z","caller":"traceutil/trace.go:171","msg":"trace[251183122] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"248.123964ms","start":"2026-03-20T10:23:45.287912Z","end":"2026-03-20T10:23:45.536036Z","steps":["trace[251183122] 'process raft request' (duration: 247.728393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:45.536257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.670313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-20T10:23:45.5363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.396342ms","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-03-20T10:23:45.536375Z","caller":"traceutil/trace.go:171","msg":"trace[552175010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4903; }","duration":"242.802476ms","start":"2026-03-20T10:23:45.293544Z","end":"2026-03-20T10:23:45.536347Z","steps":["trace[552175010] 'agreement among raft nodes before linearized reading' (duration: 242.454368ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:45.536385Z","caller":"traceutil/trace.go:171","msg":"trace[115285695] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4903; }","duration":"112.538276ms","start":"2026-03-20T10:23:45.423826Z","end":"2026-03-20T10:23:45.536364Z","steps":["trace[115285695] 'agreement among raft nodes before linearized reading' (duration: 112.2694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:45.536296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.975623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127619"} {"level":"info","ts":"2026-03-20T10:23:45.536532Z","caller":"traceutil/trace.go:171","msg":"trace[64931378] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4903; }","duration":"223.256889ms","start":"2026-03-20T10:23:45.313263Z","end":"2026-03-20T10:23:45.53652Z","steps":["trace[64931378] 'agreement among raft nodes before linearized reading' (duration: 222.862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:45.536582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.303605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:23:45.536627Z","caller":"traceutil/trace.go:171","msg":"trace[48693328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4903; }","duration":"227.398388ms","start":"2026-03-20T10:23:45.309215Z","end":"2026-03-20T10:23:45.536614Z","steps":["trace[48693328] 'agreement among raft nodes before linearized reading' (duration: 226.848415ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:45.693495Z","caller":"traceutil/trace.go:171","msg":"trace[891131097] linearizableReadLoop","detail":"{readStateIndex:5099; appliedIndex:5098; }","duration":"152.710516ms","start":"2026-03-20T10:23:45.540752Z","end":"2026-03-20T10:23:45.693462Z","steps":["trace[891131097] 'read index received' (duration: 106.850251ms)","trace[891131097] 'applied index is now lower than readState.Index' (duration: 45.858525ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:45.693542Z","caller":"traceutil/trace.go:171","msg":"trace[551652201] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"153.488414ms","start":"2026-03-20T10:23:45.540009Z","end":"2026-03-20T10:23:45.693497Z","steps":["trace[551652201] 'process raft request' (duration: 107.617389ms)","trace[551652201] 'compare' (duration: 45.66761ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:45.693786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.008203ms","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-03-20T10:23:45.693835Z","caller":"traceutil/trace.go:171","msg":"trace[1811845321] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4904; }","duration":"153.095174ms","start":"2026-03-20T10:23:45.540728Z","end":"2026-03-20T10:23:45.693823Z","steps":["trace[1811845321] 'agreement among raft nodes before linearized reading' (duration: 152.856459ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:45.728382Z","caller":"traceutil/trace.go:171","msg":"trace[1914732770] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"182.389423ms","start":"2026-03-20T10:23:45.54595Z","end":"2026-03-20T10:23:45.72834Z","steps":["trace[1914732770] 'process raft request' (duration: 182.161588ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:45.728775Z","caller":"traceutil/trace.go:171","msg":"trace[112580500] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"187.740781ms","start":"2026-03-20T10:23:45.540943Z","end":"2026-03-20T10:23:45.728684Z","steps":["trace[112580500] 'process raft request' (duration: 186.972183ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:45.936719Z","caller":"traceutil/trace.go:171","msg":"trace[1025892480] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"155.391069ms","start":"2026-03-20T10:23:45.781301Z","end":"2026-03-20T10:23:45.936692Z","steps":["trace[1025892480] 'process raft request' (duration: 155.344088ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:45.937064Z","caller":"traceutil/trace.go:171","msg":"trace[1352988326] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"202.387341ms","start":"2026-03-20T10:23:45.734657Z","end":"2026-03-20T10:23:45.937045Z","steps":["trace[1352988326] 'process raft request' (duration: 108.047329ms)","trace[1352988326] 'compare' (duration: 93.632335ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:45.937435Z","caller":"traceutil/trace.go:171","msg":"trace[1693892969] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"202.77082ms","start":"2026-03-20T10:23:45.734647Z","end":"2026-03-20T10:23:45.937418Z","steps":["trace[1693892969] 'process raft request' (duration: 201.861559ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:45.937557Z","caller":"traceutil/trace.go:171","msg":"trace[2141689372] linearizableReadLoop","detail":"{readStateIndex:5104; appliedIndex:5101; }","duration":"166.084924ms","start":"2026-03-20T10:23:45.771459Z","end":"2026-03-20T10:23:45.937543Z","steps":["trace[2141689372] 'read index received' (duration: 5.229384ms)","trace[2141689372] 'applied index is now lower than readState.Index' (duration: 160.85427ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:45.937826Z","caller":"traceutil/trace.go:171","msg":"trace[1521679474] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"156.513036ms","start":"2026-03-20T10:23:45.781289Z","end":"2026-03-20T10:23:45.937802Z","steps":["trace[1521679474] 'process raft request' (duration: 155.299358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:45.937925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.456892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-03-20T10:23:45.937988Z","caller":"traceutil/trace.go:171","msg":"trace[650107065] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4911; }","duration":"166.572275ms","start":"2026-03-20T10:23:45.7714Z","end":"2026-03-20T10:23:45.937973Z","steps":["trace[650107065] 'agreement among raft nodes before linearized reading' (duration: 166.291209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:45.938096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.700636ms","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-03-20T10:23:45.938167Z","caller":"traceutil/trace.go:171","msg":"trace[923599701] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4911; }","duration":"124.808638ms","start":"2026-03-20T10:23:45.813342Z","end":"2026-03-20T10:23:45.938151Z","steps":["trace[923599701] 'agreement among raft nodes before linearized reading' (duration: 124.707646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:46.3309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.915965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:23:46.331195Z","caller":"traceutil/trace.go:171","msg":"trace[440028460] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"387.479219ms","start":"2026-03-20T10:23:45.943687Z","end":"2026-03-20T10:23:46.331166Z","steps":["trace[440028460] 'process raft request' (duration: 101.216016ms)","trace[440028460] 'compare' (duration: 285.7365ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:46.331312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:45.943661Z","time spent":"387.594002ms","remote":"127.0.0.1:40338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:23:46.331531Z","caller":"traceutil/trace.go:171","msg":"trace[1913574610] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"387.626603ms","start":"2026-03-20T10:23:45.943865Z","end":"2026-03-20T10:23:46.331492Z","steps":["trace[1913574610] 'process raft request' (duration: 387.11397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:46.331735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:45.943849Z","time spent":"387.780157ms","remote":"127.0.0.1:40338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:23:46.331755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.427378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:71029"} {"level":"info","ts":"2026-03-20T10:23:46.331932Z","caller":"traceutil/trace.go:171","msg":"trace[1747762758] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:4914; }","duration":"387.595212ms","start":"2026-03-20T10:23:45.944318Z","end":"2026-03-20T10:23:46.331913Z","steps":["trace[1747762758] 'agreement among raft nodes before linearized reading' (duration: 387.291575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:46.331982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:45.944224Z","time spent":"387.742697ms","remote":"127.0.0.1:40238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":8,"response size":71052,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-03-20T10:23:46.332258Z","caller":"traceutil/trace.go:171","msg":"trace[2073421864] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5106; }","duration":"386.725021ms","start":"2026-03-20T10:23:45.944341Z","end":"2026-03-20T10:23:46.331066Z","steps":["trace[2073421864] 'read index received' (duration: 88.033242ms)","trace[2073421864] 'applied index is now lower than readState.Index' (duration: 298.690689ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:46.33252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.623489ms","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-03-20T10:23:46.332584Z","caller":"traceutil/trace.go:171","msg":"trace[1570686142] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4914; }","duration":"386.706901ms","start":"2026-03-20T10:23:45.945867Z","end":"2026-03-20T10:23:46.332574Z","steps":["trace[1570686142] 'agreement among raft nodes before linearized reading' (duration: 386.477985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:46.332613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:45.94586Z","time spent":"386.744681ms","remote":"127.0.0.1:40238","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-03-20T10:23:54.836776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.859649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-03-20T10:23:54.836875Z","caller":"traceutil/trace.go:171","msg":"trace[240265581] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5079; }","duration":"170.990402ms","start":"2026-03-20T10:23:54.665865Z","end":"2026-03-20T10:23:54.836856Z","steps":["trace[240265581] 'range keys from in-memory index tree' (duration: 170.736156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:54.939439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.763124ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-20T10:23:54.939562Z","caller":"traceutil/trace.go:171","msg":"trace[368663360] linearizableReadLoop","detail":"{readStateIndex:5278; appliedIndex:5277; }","duration":"208.367813ms","start":"2026-03-20T10:23:54.731167Z","end":"2026-03-20T10:23:54.939535Z","steps":["trace[368663360] 'read index received' (duration: 84.567038ms)","trace[368663360] 'applied index is now lower than readState.Index' (duration: 123.798775ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:54.93971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.64438ms","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-03-20T10:23:54.939753Z","caller":"traceutil/trace.go:171","msg":"trace[471287164] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5079; }","duration":"208.752713ms","start":"2026-03-20T10:23:54.73099Z","end":"2026-03-20T10:23:54.939743Z","steps":["trace[471287164] 'agreement among raft nodes before linearized reading' (duration: 208.64223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:54.989724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.899285ms","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-03-20T10:23:54.989846Z","caller":"traceutil/trace.go:171","msg":"trace[1923020572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5081; }","duration":"126.070889ms","start":"2026-03-20T10:23:54.86375Z","end":"2026-03-20T10:23:54.989821Z","steps":["trace[1923020572] 'agreement among raft nodes before linearized reading' (duration: 125.792323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:54.989897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.768783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-03-20T10:23:54.989973Z","caller":"traceutil/trace.go:171","msg":"trace[790767013] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5081; }","duration":"146.826024ms","start":"2026-03-20T10:23:54.843116Z","end":"2026-03-20T10:23:54.989942Z","steps":["trace[790767013] 'agreement among raft nodes before linearized reading' (duration: 146.66586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:54.989727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.009437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf9a0b41-836f-487c-a898-1c3e3ebe1ab1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-20T10:23:54.990078Z","caller":"traceutil/trace.go:171","msg":"trace[580480921] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf9a0b41-836f-487c-a898-1c3e3ebe1ab1; range_end:; response_count:1; response_revision:5081; }","duration":"188.421627ms","start":"2026-03-20T10:23:54.801624Z","end":"2026-03-20T10:23:54.990045Z","steps":["trace[580480921] 'agreement among raft nodes before linearized reading' (duration: 187.937755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:55.573924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.874052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:23:55.575211Z","caller":"traceutil/trace.go:171","msg":"trace[1121842264] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"468.460322ms","start":"2026-03-20T10:23:55.106713Z","end":"2026-03-20T10:23:55.575173Z","steps":["trace[1121842264] 'process raft request' (duration: 272.170256ms)","trace[1121842264] 'compare' (duration: 194.685998ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:55.575336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:55.106658Z","time spent":"468.626915ms","remote":"127.0.0.1:36600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7599,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:24:21.978845Z","caller":"traceutil/trace.go:171","msg":"trace[1041074745] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"106.293027ms","start":"2026-03-20T10:24:21.87253Z","end":"2026-03-20T10:24:21.978823Z","steps":["trace[1041074745] 'process raft request' (duration: 106.094102ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:25:06.639681Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2785} {"level":"info","ts":"2026-03-20T10:25:06.700057Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2785,"took":"58.564305ms","hash":1309651519,"current-db-size-bytes":40919040,"current-db-size":"41 MB","current-db-size-in-use-bytes":30093312,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-20T10:25:06.700163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1309651519,"revision":2785,"compact-revision":-1} {"level":"info","ts":"2026-03-20T10:27:01.749382Z","caller":"traceutil/trace.go:171","msg":"trace[210944579] transaction","detail":"{read_only:false; response_revision:6320; number_of_response:1; }","duration":"110.656758ms","start":"2026-03-20T10:27:01.63869Z","end":"2026-03-20T10:27:01.749347Z","steps":["trace[210944579] 'process raft request' (duration: 110.442182ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:27:01.74986Z","caller":"traceutil/trace.go:171","msg":"trace[1090601671] linearizableReadLoop","detail":"{readStateIndex:6560; appliedIndex:6557; }","duration":"107.77391ms","start":"2026-03-20T10:27:01.642072Z","end":"2026-03-20T10:27:01.749846Z","steps":["trace[1090601671] 'read index received' (duration: 35.517197ms)","trace[1090601671] 'applied index is now lower than readState.Index' (duration: 72.254993ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:27:01.749949Z","caller":"traceutil/trace.go:171","msg":"trace[516512736] transaction","detail":"{read_only:false; response_revision:6321; number_of_response:1; }","duration":"106.513089ms","start":"2026-03-20T10:27:01.643426Z","end":"2026-03-20T10:27:01.749939Z","steps":["trace[516512736] 'process raft request' (duration: 105.849483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:27:01.750292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.19034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-api-86c848f4dc-bj8jd\" ","response":"range_response_count:1 size:12107"} {"level":"info","ts":"2026-03-20T10:27:01.750363Z","caller":"traceutil/trace.go:171","msg":"trace[1966798348] range","detail":"{range_begin:/registry/pods/openstack/keystone-api-86c848f4dc-bj8jd; range_end:; response_count:1; response_revision:6321; }","duration":"108.327443ms","start":"2026-03-20T10:27:01.642018Z","end":"2026-03-20T10:27:01.750346Z","steps":["trace[1966798348] 'agreement among raft nodes before linearized reading' (duration: 108.139179ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:07.034576Z","caller":"traceutil/trace.go:171","msg":"trace[1014568891] transaction","detail":"{read_only:false; response_revision:6754; number_of_response:1; }","duration":"133.693895ms","start":"2026-03-20T10:28:06.900833Z","end":"2026-03-20T10:28:07.034527Z","steps":["trace[1014568891] 'process raft request' (duration: 131.916032ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:07.041873Z","caller":"traceutil/trace.go:171","msg":"trace[271512888] transaction","detail":"{read_only:false; response_revision:6755; number_of_response:1; }","duration":"139.277878ms","start":"2026-03-20T10:28:06.902549Z","end":"2026-03-20T10:28:07.041827Z","steps":["trace[271512888] 'process raft request' (duration: 138.991641ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:29.337744Z","caller":"traceutil/trace.go:171","msg":"trace[1185504018] transaction","detail":"{read_only:false; response_revision:6891; number_of_response:1; }","duration":"121.34013ms","start":"2026-03-20T10:28:29.216344Z","end":"2026-03-20T10:28:29.337684Z","steps":["trace[1185504018] 'process raft request' (duration: 92.867409ms)","trace[1185504018] 'compare' (duration: 28.212624ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:28:29.337849Z","caller":"traceutil/trace.go:171","msg":"trace[193150487] linearizableReadLoop","detail":"{readStateIndex:7153; appliedIndex:7152; }","duration":"120.54013ms","start":"2026-03-20T10:28:29.217256Z","end":"2026-03-20T10:28:29.337796Z","steps":["trace[193150487] 'read index received' (duration: 91.965856ms)","trace[193150487] 'applied index is now lower than readState.Index' (duration: 28.568474ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:28:29.338168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.861517ms","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-03-20T10:28:29.338278Z","caller":"traceutil/trace.go:171","msg":"trace[1970899759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6891; }","duration":"121.051862ms","start":"2026-03-20T10:28:29.217203Z","end":"2026-03-20T10:28:29.338255Z","steps":["trace[1970899759] 'agreement among raft nodes before linearized reading' (duration: 120.688473ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:59.835405Z","caller":"traceutil/trace.go:171","msg":"trace[1448713340] linearizableReadLoop","detail":"{readStateIndex:7414; appliedIndex:7413; }","duration":"329.198295ms","start":"2026-03-20T10:28:59.506173Z","end":"2026-03-20T10:28:59.835372Z","steps":["trace[1448713340] 'read index received' (duration: 328.735334ms)","trace[1448713340] 'applied index is now lower than readState.Index' (duration: 462.141µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:28:59.835453Z","caller":"traceutil/trace.go:171","msg":"trace[1393039570] transaction","detail":"{read_only:false; response_revision:7145; number_of_response:1; }","duration":"329.78505ms","start":"2026-03-20T10:28:59.505636Z","end":"2026-03-20T10:28:59.835421Z","steps":["trace[1393039570] 'process raft request' (duration: 329.541924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:28:59.835697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.440826ms","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-03-20T10:28:59.83573Z","caller":"traceutil/trace.go:171","msg":"trace[286981913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7145; }","duration":"261.530948ms","start":"2026-03-20T10:28:59.57419Z","end":"2026-03-20T10:28:59.835721Z","steps":["trace[286981913] 'agreement among raft nodes before linearized reading' (duration: 261.378995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:28:59.835725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:28:59.505611Z","time spent":"329.912293ms","remote":"127.0.0.1:40338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:28:59.835744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.505882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T10:28:59.835856Z","caller":"traceutil/trace.go:171","msg":"trace[176504046] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:7145; }","duration":"329.702907ms","start":"2026-03-20T10:28:59.506127Z","end":"2026-03-20T10:28:59.83583Z","steps":["trace[176504046] 'agreement among raft nodes before linearized reading' (duration: 329.383589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:28:59.835893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:28:59.506103Z","time spent":"329.780259ms","remote":"127.0.0.1:40498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-20T10:28:59.835973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.739082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T10:28:59.835986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.678006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-20T10:28:59.836026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.83369ms","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-03-20T10:28:59.83614Z","caller":"traceutil/trace.go:171","msg":"trace[405965257] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:7145; }","duration":"257.885621ms","start":"2026-03-20T10:28:59.578224Z","end":"2026-03-20T10:28:59.836109Z","steps":["trace[405965257] 'agreement among raft nodes before linearized reading' (duration: 257.538833ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:59.836168Z","caller":"traceutil/trace.go:171","msg":"trace[331442581] 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:7145; }","duration":"186.012714ms","start":"2026-03-20T10:28:59.650127Z","end":"2026-03-20T10:28:59.836139Z","steps":["trace[331442581] 'agreement among raft nodes before linearized reading' (duration: 185.585004ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:59.836086Z","caller":"traceutil/trace.go:171","msg":"trace[1432646786] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:7145; }","duration":"258.910356ms","start":"2026-03-20T10:28:59.577151Z","end":"2026-03-20T10:28:59.836061Z","steps":["trace[1432646786] 'agreement among raft nodes before linearized reading' (duration: 258.678031ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:00.017345Z","caller":"traceutil/trace.go:171","msg":"trace[1146246987] transaction","detail":"{read_only:false; response_revision:7146; number_of_response:1; }","duration":"174.773536ms","start":"2026-03-20T10:28:59.842533Z","end":"2026-03-20T10:29:00.017307Z","steps":["trace[1146246987] 'process raft request' (duration: 145.867435ms)","trace[1146246987] 'compare' (duration: 28.732477ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:00.025169Z","caller":"traceutil/trace.go:171","msg":"trace[262134146] transaction","detail":"{read_only:false; response_revision:7147; number_of_response:1; }","duration":"124.810862ms","start":"2026-03-20T10:28:59.900341Z","end":"2026-03-20T10:29:00.025152Z","steps":["trace[262134146] 'process raft request' (duration: 124.223958ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:00.671221Z","caller":"traceutil/trace.go:171","msg":"trace[718343112] transaction","detail":"{read_only:false; response_revision:7151; number_of_response:1; }","duration":"203.000651ms","start":"2026-03-20T10:29:00.46819Z","end":"2026-03-20T10:29:00.671191Z","steps":["trace[718343112] 'process raft request' (duration: 202.806255ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:00.671521Z","caller":"traceutil/trace.go:171","msg":"trace[1201841056] linearizableReadLoop","detail":"{readStateIndex:7421; appliedIndex:7421; }","duration":"199.793854ms","start":"2026-03-20T10:29:00.471715Z","end":"2026-03-20T10:29:00.671508Z","steps":["trace[1201841056] 'read index received' (duration: 199.786164ms)","trace[1201841056] 'applied index is now lower than readState.Index' (duration: 6.13µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:29:00.671777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.054031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9wfjm\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-20T10:29:00.671822Z","caller":"traceutil/trace.go:171","msg":"trace[48611853] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9wfjm; range_end:; response_count:1; response_revision:7151; }","duration":"200.153503ms","start":"2026-03-20T10:29:00.471654Z","end":"2026-03-20T10:29:00.671808Z","steps":["trace[48611853] 'agreement among raft nodes before linearized reading' (duration: 199.950158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:00.731678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.229338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-03-20T10:29:00.731798Z","caller":"traceutil/trace.go:171","msg":"trace[891077307] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:7152; }","duration":"119.416052ms","start":"2026-03-20T10:29:00.612353Z","end":"2026-03-20T10:29:00.731769Z","steps":["trace[891077307] 'agreement among raft nodes before linearized reading' (duration: 119.088134ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:02.966975Z","caller":"traceutil/trace.go:171","msg":"trace[1315530729] linearizableReadLoop","detail":"{readStateIndex:7431; appliedIndex:7430; }","duration":"290.05961ms","start":"2026-03-20T10:29:02.676889Z","end":"2026-03-20T10:29:02.966949Z","steps":["trace[1315530729] 'read index received' (duration: 289.949807ms)","trace[1315530729] 'applied index is now lower than readState.Index' (duration: 108.513µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:02.967193Z","caller":"traceutil/trace.go:171","msg":"trace[1434676396] transaction","detail":"{read_only:false; response_revision:7161; number_of_response:1; }","duration":"337.034993ms","start":"2026-03-20T10:29:02.630138Z","end":"2026-03-20T10:29:02.967173Z","steps":["trace[1434676396] 'process raft request' (duration: 336.623863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:02.967352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:29:02.630106Z","time spent":"337.136515ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:29:02.967761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.839649ms","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-03-20T10:29:02.967894Z","caller":"traceutil/trace.go:171","msg":"trace[993852461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7161; }","duration":"291.036394ms","start":"2026-03-20T10:29:02.676827Z","end":"2026-03-20T10:29:02.967863Z","steps":["trace[993852461] 'agreement among raft nodes before linearized reading' (duration: 290.631305ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:03.149674Z","caller":"traceutil/trace.go:171","msg":"trace[882729422] linearizableReadLoop","detail":"{readStateIndex:7433; appliedIndex:7431; }","duration":"182.5074ms","start":"2026-03-20T10:29:02.967125Z","end":"2026-03-20T10:29:03.149633Z","steps":["trace[882729422] 'read index received' (duration: 182.287895ms)","trace[882729422] 'applied index is now lower than readState.Index' (duration: 217.575µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:03.149827Z","caller":"traceutil/trace.go:171","msg":"trace[736541976] transaction","detail":"{read_only:false; response_revision:7162; number_of_response:1; }","duration":"412.382723ms","start":"2026-03-20T10:29:02.737414Z","end":"2026-03-20T10:29:03.149797Z","steps":["trace[736541976] 'process raft request' (duration: 411.998544ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:03.149844Z","caller":"traceutil/trace.go:171","msg":"trace[1641395923] transaction","detail":"{read_only:false; response_revision:7163; number_of_response:1; }","duration":"220.891138ms","start":"2026-03-20T10:29:02.928938Z","end":"2026-03-20T10:29:03.149829Z","steps":["trace[1641395923] 'process raft request' (duration: 220.643682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:03.150024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:29:02.737381Z","time spent":"412.504505ms","remote":"127.0.0.1:40338","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-03-20T10:29:03.150063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.754164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:29:03.150173Z","caller":"traceutil/trace.go:171","msg":"trace[1891379926] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:7163; }","duration":"220.982719ms","start":"2026-03-20T10:29:02.929164Z","end":"2026-03-20T10:29:03.150147Z","steps":["trace[1891379926] 'agreement among raft nodes before linearized reading' (duration: 220.740864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:03.150229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.069204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9wfjm\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-20T10:29:03.150332Z","caller":"traceutil/trace.go:171","msg":"trace[1145379053] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9wfjm; range_end:; response_count:1; response_revision:7163; }","duration":"409.231588ms","start":"2026-03-20T10:29:02.74108Z","end":"2026-03-20T10:29:03.150312Z","steps":["trace[1145379053] 'agreement among raft nodes before linearized reading' (duration: 408.882289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:03.15038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:29:02.741064Z","time spent":"409.308481ms","remote":"127.0.0.1:40238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-9wfjm\" "} {"level":"warn","ts":"2026-03-20T10:29:03.153951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.252533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:29:03.154043Z","caller":"traceutil/trace.go:171","msg":"trace[787187189] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:7164; }","duration":"161.387306ms","start":"2026-03-20T10:29:02.992632Z","end":"2026-03-20T10:29:03.15402Z","steps":["trace[787187189] 'agreement among raft nodes before linearized reading' (duration: 161.230633ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:03.153965Z","caller":"traceutil/trace.go:171","msg":"trace[1840383988] transaction","detail":"{read_only:false; response_revision:7164; number_of_response:1; }","duration":"180.256357ms","start":"2026-03-20T10:29:02.973666Z","end":"2026-03-20T10:29:03.153922Z","steps":["trace[1840383988] 'process raft request' (duration: 180.069463ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:04.150343Z","caller":"traceutil/trace.go:171","msg":"trace[1223834742] linearizableReadLoop","detail":"{readStateIndex:7440; appliedIndex:7439; }","duration":"100.07749ms","start":"2026-03-20T10:29:04.050235Z","end":"2026-03-20T10:29:04.150312Z","steps":["trace[1223834742] 'read index received' (duration: 99.869215ms)","trace[1223834742] 'applied index is now lower than readState.Index' (duration: 206.695µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:04.150484Z","caller":"traceutil/trace.go:171","msg":"trace[2076567933] transaction","detail":"{read_only:false; response_revision:7170; number_of_response:1; }","duration":"171.423457ms","start":"2026-03-20T10:29:03.979016Z","end":"2026-03-20T10:29:04.150439Z","steps":["trace[2076567933] 'process raft request' (duration: 171.106708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:04.15064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.388999ms","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-03-20T10:29:04.150708Z","caller":"traceutil/trace.go:171","msg":"trace[1641852716] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7170; }","duration":"100.528862ms","start":"2026-03-20T10:29:04.050156Z","end":"2026-03-20T10:29:04.150685Z","steps":["trace[1641852716] 'agreement among raft nodes before linearized reading' (duration: 100.320287ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:04.324956Z","caller":"traceutil/trace.go:171","msg":"trace[38243819] transaction","detail":"{read_only:false; response_revision:7171; number_of_response:1; }","duration":"168.498475ms","start":"2026-03-20T10:29:04.156422Z","end":"2026-03-20T10:29:04.32492Z","steps":["trace[38243819] 'process raft request' (duration: 70.166806ms)","trace[38243819] 'compare' (duration: 98.199356ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:04.342774Z","caller":"traceutil/trace.go:171","msg":"trace[1573603551] transaction","detail":"{read_only:false; response_revision:7172; number_of_response:1; }","duration":"186.061505ms","start":"2026-03-20T10:29:04.156683Z","end":"2026-03-20T10:29:04.342744Z","steps":["trace[1573603551] 'process raft request' (duration: 185.790828ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:04.342829Z","caller":"traceutil/trace.go:171","msg":"trace[1490033066] linearizableReadLoop","detail":"{readStateIndex:7443; appliedIndex:7440; }","duration":"152.571875ms","start":"2026-03-20T10:29:04.19023Z","end":"2026-03-20T10:29:04.342802Z","steps":["trace[1490033066] 'read index received' (duration: 36.276987ms)","trace[1490033066] 'applied index is now lower than readState.Index' (duration: 116.291768ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:04.342826Z","caller":"traceutil/trace.go:171","msg":"trace[190984952] transaction","detail":"{read_only:false; response_revision:7173; number_of_response:1; }","duration":"184.633742ms","start":"2026-03-20T10:29:04.158153Z","end":"2026-03-20T10:29:04.342787Z","steps":["trace[190984952] 'process raft request' (duration: 184.517699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:04.343128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.835921ms","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-03-20T10:29:04.34319Z","caller":"traceutil/trace.go:171","msg":"trace[1849920167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7173; }","duration":"153.002595ms","start":"2026-03-20T10:29:04.190176Z","end":"2026-03-20T10:29:04.343178Z","steps":["trace[1849920167] 'agreement among raft nodes before linearized reading' (duration: 152.685588ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:04.482998Z","caller":"traceutil/trace.go:171","msg":"trace[101584704] transaction","detail":"{read_only:false; response_revision:7175; number_of_response:1; }","duration":"131.753898ms","start":"2026-03-20T10:29:04.351214Z","end":"2026-03-20T10:29:04.482968Z","steps":["trace[101584704] 'process raft request' (duration: 125.53542ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:04.713368Z","caller":"traceutil/trace.go:171","msg":"trace[470432814] linearizableReadLoop","detail":"{readStateIndex:7446; appliedIndex:7445; }","duration":"217.972927ms","start":"2026-03-20T10:29:04.495366Z","end":"2026-03-20T10:29:04.713339Z","steps":["trace[470432814] 'read index received' (duration: 217.901075ms)","trace[470432814] 'applied index is now lower than readState.Index' (duration: 70.472µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:04.713465Z","caller":"traceutil/trace.go:171","msg":"trace[1351001616] transaction","detail":"{read_only:false; response_revision:7176; number_of_response:1; }","duration":"224.276419ms","start":"2026-03-20T10:29:04.48914Z","end":"2026-03-20T10:29:04.713417Z","steps":["trace[1351001616] 'process raft request' (duration: 224.024893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:04.71357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.186043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:29:04.713671Z","caller":"traceutil/trace.go:171","msg":"trace[591568131] range","detail":"{range_begin:/registry/jobs/openstack/barbican-rabbit-init; range_end:; response_count:0; response_revision:7176; }","duration":"218.304856ms","start":"2026-03-20T10:29:04.495347Z","end":"2026-03-20T10:29:04.713652Z","steps":["trace[591568131] 'agreement among raft nodes before linearized reading' (duration: 218.113902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:04.877628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.831718ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-20T10:29:04.889887Z","caller":"traceutil/trace.go:171","msg":"trace[1891753455] transaction","detail":"{read_only:false; response_revision:7177; number_of_response:1; }","duration":"144.43552ms","start":"2026-03-20T10:29:04.74542Z","end":"2026-03-20T10:29:04.889856Z","steps":["trace[1891753455] 'process raft request' (duration: 144.138383ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:05.10482Z","caller":"traceutil/trace.go:171","msg":"trace[1745478274] transaction","detail":"{read_only:false; response_revision:7178; number_of_response:1; }","duration":"201.109906ms","start":"2026-03-20T10:29:04.903667Z","end":"2026-03-20T10:29:05.104777Z","steps":["trace[1745478274] 'process raft request' (duration: 192.208513ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:05.258991Z","caller":"traceutil/trace.go:171","msg":"trace[313458540] linearizableReadLoop","detail":"{readStateIndex:7450; appliedIndex:7449; }","duration":"146.257035ms","start":"2026-03-20T10:29:05.112704Z","end":"2026-03-20T10:29:05.258961Z","steps":["trace[313458540] 'read index received' (duration: 146.170933ms)","trace[313458540] 'applied index is now lower than readState.Index' (duration: 84.752µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:05.25939Z","caller":"traceutil/trace.go:171","msg":"trace[1202001978] transaction","detail":"{read_only:false; response_revision:7179; number_of_response:1; }","duration":"147.127094ms","start":"2026-03-20T10:29:05.112206Z","end":"2026-03-20T10:29:05.259333Z","steps":["trace[1202001978] 'process raft request' (duration: 146.570801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:05.259479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.746496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:29:05.259586Z","caller":"traceutil/trace.go:171","msg":"trace[401754128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7179; }","duration":"146.91992ms","start":"2026-03-20T10:29:05.112643Z","end":"2026-03-20T10:29:05.259563Z","steps":["trace[401754128] 'agreement among raft nodes before linearized reading' (duration: 146.694264ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:05.445856Z","caller":"traceutil/trace.go:171","msg":"trace[930901150] transaction","detail":"{read_only:false; response_revision:7181; number_of_response:1; }","duration":"288.453632ms","start":"2026-03-20T10:29:05.157369Z","end":"2026-03-20T10:29:05.445823Z","steps":["trace[930901150] 'process raft request' (duration: 288.265228ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:05.44599Z","caller":"traceutil/trace.go:171","msg":"trace[504346426] transaction","detail":"{read_only:false; response_revision:7184; number_of_response:1; }","duration":"179.973089ms","start":"2026-03-20T10:29:05.265974Z","end":"2026-03-20T10:29:05.445947Z","steps":["trace[504346426] 'process raft request' (duration: 179.898488ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:05.446064Z","caller":"traceutil/trace.go:171","msg":"trace[331966250] transaction","detail":"{read_only:false; response_revision:7180; number_of_response:1; }","duration":"289.693661ms","start":"2026-03-20T10:29:05.15636Z","end":"2026-03-20T10:29:05.446053Z","steps":["trace[331966250] 'process raft request' (duration: 229.146824ms)","trace[331966250] 'compare' (duration: 59.895311ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:05.446249Z","caller":"traceutil/trace.go:171","msg":"trace[87677881] transaction","detail":"{read_only:false; response_revision:7182; number_of_response:1; }","duration":"182.08164ms","start":"2026-03-20T10:29:05.264161Z","end":"2026-03-20T10:29:05.446242Z","steps":["trace[87677881] 'process raft request' (duration: 181.563328ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:05.44639Z","caller":"traceutil/trace.go:171","msg":"trace[1284135844] linearizableReadLoop","detail":"{readStateIndex:7452; appliedIndex:7450; }","duration":"187.270554ms","start":"2026-03-20T10:29:05.25911Z","end":"2026-03-20T10:29:05.446381Z","steps":["trace[1284135844] 'read index received' (duration: 126.533223ms)","trace[1284135844] 'applied index is now lower than readState.Index' (duration: 60.736561ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:05.446384Z","caller":"traceutil/trace.go:171","msg":"trace[854230028] transaction","detail":"{read_only:false; response_revision:7183; number_of_response:1; }","duration":"181.921797ms","start":"2026-03-20T10:29:05.264427Z","end":"2026-03-20T10:29:05.446348Z","steps":["trace[854230028] 'process raft request' (duration: 181.376264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:05.446705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.683656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:29:05.44681Z","caller":"traceutil/trace.go:171","msg":"trace[1233819901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7184; }","duration":"185.84652ms","start":"2026-03-20T10:29:05.260939Z","end":"2026-03-20T10:29:05.446785Z","steps":["trace[1233819901] 'agreement among raft nodes before linearized reading' (duration: 185.670016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:05.446873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.859177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-20T10:29:05.446902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.322898ms","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-03-20T10:29:05.446936Z","caller":"traceutil/trace.go:171","msg":"trace[1770968485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7184; }","duration":"162.42342ms","start":"2026-03-20T10:29:05.284504Z","end":"2026-03-20T10:29:05.446927Z","steps":["trace[1770968485] 'agreement among raft nodes before linearized reading' (duration: 162.276957ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:05.446937Z","caller":"traceutil/trace.go:171","msg":"trace[1580164258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7184; }","duration":"287.961379ms","start":"2026-03-20T10:29:05.158962Z","end":"2026-03-20T10:29:05.446924Z","steps":["trace[1580164258] 'agreement among raft nodes before linearized reading' (duration: 287.767985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:05.447064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.118666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9wfjm\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-20T10:29:05.447137Z","caller":"traceutil/trace.go:171","msg":"trace[1872950215] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9wfjm; range_end:; response_count:1; response_revision:7184; }","duration":"286.264079ms","start":"2026-03-20T10:29:05.160861Z","end":"2026-03-20T10:29:05.447125Z","steps":["trace[1872950215] 'agreement among raft nodes before linearized reading' (duration: 286.027183ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:06.646794Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5654} {"level":"info","ts":"2026-03-20T10:30:06.743052Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5654,"took":"93.992587ms","hash":2037711357,"current-db-size-bytes":41787392,"current-db-size":"42 MB","current-db-size-in-use-bytes":21368832,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-03-20T10:30:06.743157Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2037711357,"revision":5654,"compact-revision":2785} {"level":"info","ts":"2026-03-20T10:30:44.550942Z","caller":"traceutil/trace.go:171","msg":"trace[362391402] transaction","detail":"{read_only:false; response_revision:7794; number_of_response:1; }","duration":"107.372727ms","start":"2026-03-20T10:30:44.443521Z","end":"2026-03-20T10:30:44.550894Z","steps":["trace[362391402] 'process raft request' (duration: 107.116021ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:48.512248Z","caller":"traceutil/trace.go:171","msg":"trace[245288390] transaction","detail":"{read_only:false; response_revision:7941; number_of_response:1; }","duration":"177.537911ms","start":"2026-03-20T10:30:48.334688Z","end":"2026-03-20T10:30:48.512226Z","steps":["trace[245288390] 'process raft request' (duration: 177.297885ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:48.512229Z","caller":"traceutil/trace.go:171","msg":"trace[526539260] linearizableReadLoop","detail":"{readStateIndex:8238; appliedIndex:8237; }","duration":"172.778896ms","start":"2026-03-20T10:30:48.339419Z","end":"2026-03-20T10:30:48.512198Z","steps":["trace[526539260] 'read index received' (duration: 172.545991ms)","trace[526539260] 'applied index is now lower than readState.Index' (duration: 231.145µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:30:48.513208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.73267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224159"} {"level":"info","ts":"2026-03-20T10:30:48.513353Z","caller":"traceutil/trace.go:171","msg":"trace[1793885571] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7941; }","duration":"173.899514ms","start":"2026-03-20T10:30:48.339408Z","end":"2026-03-20T10:30:48.513308Z","steps":["trace[1793885571] 'agreement among raft nodes before linearized reading' (duration: 172.859368ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:48.78626Z","caller":"traceutil/trace.go:171","msg":"trace[337423339] transaction","detail":"{read_only:false; response_revision:7942; number_of_response:1; }","duration":"267.796577ms","start":"2026-03-20T10:30:48.518418Z","end":"2026-03-20T10:30:48.786214Z","steps":["trace[337423339] 'process raft request' (duration: 239.939848ms)","trace[337423339] 'compare' (duration: 27.388818ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:30:48.78661Z","caller":"traceutil/trace.go:171","msg":"trace[1774372085] transaction","detail":"{read_only:false; response_revision:7943; number_of_response:1; }","duration":"221.432734ms","start":"2026-03-20T10:30:48.565126Z","end":"2026-03-20T10:30:48.786559Z","steps":["trace[1774372085] 'process raft request' (duration: 221.223549ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:50.738079Z","caller":"traceutil/trace.go:171","msg":"trace[506911947] transaction","detail":"{read_only:false; response_revision:7950; number_of_response:1; }","duration":"617.937862ms","start":"2026-03-20T10:30:50.120109Z","end":"2026-03-20T10:30:50.738047Z","steps":["trace[506911947] 'process raft request' (duration: 617.751777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:50.738256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:50.120081Z","time spent":"618.082164ms","remote":"127.0.0.1:40338","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-03-20T10:30:50.73993Z","caller":"traceutil/trace.go:171","msg":"trace[1401236925] linearizableReadLoop","detail":"{readStateIndex:8249; appliedIndex:8247; }","duration":"222.705885ms","start":"2026-03-20T10:30:50.517213Z","end":"2026-03-20T10:30:50.739919Z","steps":["trace[1401236925] 'read index received' (duration: 220.714378ms)","trace[1401236925] 'applied index is now lower than readState.Index' (duration: 1.990567ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:30:50.74013Z","caller":"traceutil/trace.go:171","msg":"trace[858651439] transaction","detail":"{read_only:false; response_revision:7951; number_of_response:1; }","duration":"495.742078ms","start":"2026-03-20T10:30:50.2443Z","end":"2026-03-20T10:30:50.740042Z","steps":["trace[858651439] 'process raft request' (duration: 495.547392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:50.740207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.981982ms","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-03-20T10:30:50.740303Z","caller":"traceutil/trace.go:171","msg":"trace[247107187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7951; }","duration":"223.141526ms","start":"2026-03-20T10:30:50.517141Z","end":"2026-03-20T10:30:50.740283Z","steps":["trace[247107187] 'agreement among raft nodes before linearized reading' (duration: 222.8951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:50.740366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.333484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9wfjm\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-03-20T10:30:50.740422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:50.244274Z","time spent":"495.985164ms","remote":"127.0.0.1:40338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:30:50.740471Z","caller":"traceutil/trace.go:171","msg":"trace[2014505793] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9wfjm; range_end:; response_count:1; response_revision:7951; }","duration":"214.512618ms","start":"2026-03-20T10:30:50.525934Z","end":"2026-03-20T10:30:50.740447Z","steps":["trace[2014505793] 'agreement among raft nodes before linearized reading' (duration: 214.187031ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:52.998005Z","caller":"traceutil/trace.go:171","msg":"trace[1313015279] transaction","detail":"{read_only:false; response_revision:7965; number_of_response:1; }","duration":"127.778907ms","start":"2026-03-20T10:30:52.870192Z","end":"2026-03-20T10:30:52.997971Z","steps":["trace[1313015279] 'process raft request' (duration: 127.577851ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:36.178281Z","caller":"traceutil/trace.go:171","msg":"trace[777240590] transaction","detail":"{read_only:false; response_revision:8225; number_of_response:1; }","duration":"169.136982ms","start":"2026-03-20T10:31:36.009107Z","end":"2026-03-20T10:31:36.178244Z","steps":["trace[777240590] 'process raft request' (duration: 168.871776ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:51.298169Z","caller":"traceutil/trace.go:171","msg":"trace[44476060] transaction","detail":"{read_only:false; response_revision:8341; number_of_response:1; }","duration":"117.826984ms","start":"2026-03-20T10:31:51.180316Z","end":"2026-03-20T10:31:51.298143Z","steps":["trace[44476060] 'process raft request' (duration: 117.781093ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:51.298271Z","caller":"traceutil/trace.go:171","msg":"trace[1420207390] transaction","detail":"{read_only:false; response_revision:8339; number_of_response:1; }","duration":"129.999406ms","start":"2026-03-20T10:31:51.168243Z","end":"2026-03-20T10:31:51.298242Z","steps":["trace[1420207390] 'process raft request' (duration: 124.895274ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:51.298359Z","caller":"traceutil/trace.go:171","msg":"trace[429182141] transaction","detail":"{read_only:false; response_revision:8340; number_of_response:1; }","duration":"128.159352ms","start":"2026-03-20T10:31:51.17019Z","end":"2026-03-20T10:31:51.298349Z","steps":["trace[429182141] 'process raft request' (duration: 127.806274ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:51.311955Z","caller":"traceutil/trace.go:171","msg":"trace[1635650885] transaction","detail":"{read_only:false; response_revision:8342; number_of_response:1; }","duration":"118.307246ms","start":"2026-03-20T10:31:51.193601Z","end":"2026-03-20T10:31:51.311909Z","steps":["trace[1635650885] 'process raft request' (duration: 118.117411ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:33:11.881357Z","caller":"traceutil/trace.go:171","msg":"trace[1792295193] transaction","detail":"{read_only:false; response_revision:9066; number_of_response:1; }","duration":"261.207748ms","start":"2026-03-20T10:33:11.620123Z","end":"2026-03-20T10:33:11.881331Z","steps":["trace[1792295193] 'process raft request' (duration: 261.021974ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:33:12.076601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.656178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:33:12.076986Z","caller":"traceutil/trace.go:171","msg":"trace[1020509070] linearizableReadLoop","detail":"{readStateIndex:9407; appliedIndex:9404; }","duration":"296.145307ms","start":"2026-03-20T10:33:11.780816Z","end":"2026-03-20T10:33:12.076961Z","steps":["trace[1020509070] 'read index received' (duration: 100.410456ms)","trace[1020509070] 'applied index is now lower than readState.Index' (duration: 195.732711ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:33:12.077023Z","caller":"traceutil/trace.go:171","msg":"trace[1747123663] transaction","detail":"{read_only:false; response_revision:9067; number_of_response:1; }","duration":"456.215222ms","start":"2026-03-20T10:33:11.620771Z","end":"2026-03-20T10:33:12.076986Z","steps":["trace[1747123663] 'process raft request' (duration: 283.070243ms)","trace[1747123663] 'compare' (duration: 172.457473ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:33:12.077186Z","caller":"traceutil/trace.go:171","msg":"trace[1192589020] transaction","detail":"{read_only:false; response_revision:9068; number_of_response:1; }","duration":"418.107429ms","start":"2026-03-20T10:33:11.659064Z","end":"2026-03-20T10:33:12.077171Z","steps":["trace[1192589020] 'process raft request' (duration: 417.658238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:33:12.077301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:33:11.620741Z","time spent":"456.399487ms","remote":"127.0.0.1:40338","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-03-20T10:33:12.077371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.556716ms","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-03-20T10:33:12.077419Z","caller":"traceutil/trace.go:171","msg":"trace[626536452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9068; }","duration":"296.646638ms","start":"2026-03-20T10:33:11.780758Z","end":"2026-03-20T10:33:12.077404Z","steps":["trace[626536452] 'agreement among raft nodes before linearized reading' (duration: 296.461814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:33:12.077402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:33:11.659032Z","time spent":"418.198871ms","remote":"127.0.0.1:40338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:33:12.077601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.851249ms","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-03-20T10:33:12.077632Z","caller":"traceutil/trace.go:171","msg":"trace[310735952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9068; }","duration":"151.92097ms","start":"2026-03-20T10:33:11.925702Z","end":"2026-03-20T10:33:12.077623Z","steps":["trace[310735952] 'agreement among raft nodes before linearized reading' (duration: 151.819968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:33:12.078565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.350147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9wfjm\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-20T10:33:12.078678Z","caller":"traceutil/trace.go:171","msg":"trace[1257469133] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9wfjm; range_end:; response_count:1; response_revision:9068; }","duration":"179.542582ms","start":"2026-03-20T10:33:11.899111Z","end":"2026-03-20T10:33:12.078654Z","steps":["trace[1257469133] 'agreement among raft nodes before linearized reading' (duration: 178.148468ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:34:44.423432Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8c0a14645b8502c5","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-20T10:34:44.431289Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-20T10:34:44.431425Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-20T10:35:06.65583Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7566} {"level":"info","ts":"2026-03-20T10:35:06.724028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7566,"took":"65.599692ms","hash":4149985227,"current-db-size-bytes":41787392,"current-db-size":"42 MB","current-db-size-in-use-bytes":24870912,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-20T10:35:06.724113Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4149985227,"revision":7566,"compact-revision":5654} {"level":"info","ts":"2026-03-20T10:40:06.662652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9820} {"level":"info","ts":"2026-03-20T10:40:06.755653Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9820,"took":"89.95609ms","hash":1140220596,"current-db-size-bytes":41787392,"current-db-size":"42 MB","current-db-size-in-use-bytes":19030016,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-03-20T10:40:06.755771Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1140220596,"revision":9820,"compact-revision":7566}