{"level":"warn","ts":"2026-04-07T12:57:00.791475Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T12:57:00.791558Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.99: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.99:2380","--initial-cluster=instance=https://199.19.213.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.99: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-04-07T12:57:00.791626Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T12:57:00.791638Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-04-07T12:57:00.791653Z","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-04-07T12:57:00.792374Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"]} {"level":"info","ts":"2026-04-07T12:57:00.792444Z","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.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.99: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-04-07T12:57:00.7972Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.554413ms"} {"level":"info","ts":"2026-04-07T12:57:00.803084Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4da37caf00b87dfe","cluster-id":"221bed930aa36d2e"} {"level":"info","ts":"2026-04-07T12:57:00.803164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T12:57:00.803186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 0"} {"level":"info","ts":"2026-04-07T12:57:00.80321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4da37caf00b87dfe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T12:57:00.803223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 1"} {"level":"info","ts":"2026-04-07T12:57:00.803264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"warn","ts":"2026-04-07T12:57:00.80996Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T12:57:00.811766Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T12:57:00.813438Z","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-04-07T12:57:00.815797Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4da37caf00b87dfe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T12:57:00.815938Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4da37caf00b87dfe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T12:57:00.816124Z","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-04-07T12:57:00.816264Z","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-04-07T12:57:00.816289Z","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-04-07T12:57:00.819384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"info","ts":"2026-04-07T12:57:00.819591Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","added-peer-id":"4da37caf00b87dfe","added-peer-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-04-07T12:57:00.82202Z","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-04-07T12:57:00.8221Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-04-07T12:57:00.822297Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-04-07T12:57:00.822424Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4da37caf00b87dfe","initial-advertise-peer-urls":["https://199.19.213.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T12:57:00.822484Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T12:57:01.303844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T12:57:01.303891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T12:57:01.303923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgPreVoteResp from 4da37caf00b87dfe at term 1"} {"level":"info","ts":"2026-04-07T12:57:01.303937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became candidate at term 2"} {"level":"info","ts":"2026-04-07T12:57:01.303945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgVoteResp from 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-04-07T12:57:01.30406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became leader at term 2"} {"level":"info","ts":"2026-04-07T12:57:01.304069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4da37caf00b87dfe elected leader 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-04-07T12:57:01.304899Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:57:01.305778Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4da37caf00b87dfe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.99:2379]}","request-path":"/0/members/4da37caf00b87dfe/attributes","cluster-id":"221bed930aa36d2e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T12:57:01.305948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:57:01.306023Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:57:01.306558Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:57:01.306806Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:57:01.306864Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:57:01.307276Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T12:57:01.307325Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T12:57:01.308529Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.99:2379"} {"level":"info","ts":"2026-04-07T12:57:01.308817Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T12:57:03.003422Z","caller":"traceutil/trace.go:171","msg":"trace[580068080] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"142.958418ms","start":"2026-04-07T12:57:02.860435Z","end":"2026-04-07T12:57:03.003394Z","steps":["trace[580068080] 'process raft request' (duration: 142.797114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:03.160404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.505434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T12:57:03.160511Z","caller":"traceutil/trace.go:171","msg":"trace[543674185] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:70; }","duration":"109.67629ms","start":"2026-04-07T12:57:03.050813Z","end":"2026-04-07T12:57:03.160489Z","steps":["trace[543674185] 'range keys from in-memory index tree' (duration: 109.397192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:09.360961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.752392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:57:09.361073Z","caller":"traceutil/trace.go:171","msg":"trace[1163269261] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:338; }","duration":"496.334886ms","start":"2026-04-07T12:57:08.864728Z","end":"2026-04-07T12:57:09.361063Z","steps":["trace[1163269261] 'read index received' (duration: 255.242874ms)","trace[1163269261] 'applied index is now lower than readState.Index' (duration: 241.091152ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:57:09.361164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.441069ms","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-04-07T12:57:09.361129Z","caller":"traceutil/trace.go:171","msg":"trace[103155257] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"513.62491ms","start":"2026-04-07T12:57:08.847472Z","end":"2026-04-07T12:57:09.361096Z","steps":["trace[103155257] 'process raft request' (duration: 272.461836ms)","trace[103155257] 'compare' (duration: 240.64746ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:09.361186Z","caller":"traceutil/trace.go:171","msg":"trace[1975818063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:326; }","duration":"496.492471ms","start":"2026-04-07T12:57:08.864688Z","end":"2026-04-07T12:57:09.36118Z","steps":["trace[1975818063] 'agreement among raft nodes before linearized reading' (duration: 496.428409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:09.361206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:08.864675Z","time spent":"496.527672ms","remote":"127.0.0.1:55196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T12:57:09.361292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:08.847443Z","time spent":"513.763445ms","remote":"127.0.0.1:55112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":203,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:57:09.361441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.674375ms","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-04-07T12:57:09.361459Z","caller":"traceutil/trace.go:171","msg":"trace[1602576996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:326; }","duration":"334.726127ms","start":"2026-04-07T12:57:09.026729Z","end":"2026-04-07T12:57:09.361455Z","steps":["trace[1602576996] 'agreement among raft nodes before linearized reading' (duration: 334.693766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:09.361471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:09.026707Z","time spent":"334.761227ms","remote":"127.0.0.1:55196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T12:57:09.714596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.790755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:57:09.714817Z","caller":"traceutil/trace.go:171","msg":"trace[563491805] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:339; }","duration":"346.923511ms","start":"2026-04-07T12:57:09.367869Z","end":"2026-04-07T12:57:09.714793Z","steps":["trace[563491805] 'read index received' (duration: 106.660233ms)","trace[563491805] 'applied index is now lower than readState.Index' (duration: 240.261808ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:57:09.714936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.065645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:57:09.714853Z","caller":"traceutil/trace.go:171","msg":"trace[626765954] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"349.253279ms","start":"2026-04-07T12:57:09.365562Z","end":"2026-04-07T12:57:09.714815Z","steps":["trace[626765954] 'process raft request' (duration: 108.956389ms)","trace[626765954] 'compare' (duration: 239.643611ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:09.715035Z","caller":"traceutil/trace.go:171","msg":"trace[1800214265] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"348.62761ms","start":"2026-04-07T12:57:09.366373Z","end":"2026-04-07T12:57:09.715001Z","steps":["trace[1800214265] 'process raft request' (duration: 348.343912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:09.715217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:09.366353Z","time spent":"348.757584ms","remote":"127.0.0.1:55196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:57:09.714975Z","caller":"traceutil/trace.go:171","msg":"trace[475607491] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:328; }","duration":"347.105516ms","start":"2026-04-07T12:57:09.367858Z","end":"2026-04-07T12:57:09.714963Z","steps":["trace[475607491] 'agreement among raft nodes before linearized reading' (duration: 347.020764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:09.715526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:09.367826Z","time spent":"347.680103ms","remote":"127.0.0.1:55112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" "} {"level":"warn","ts":"2026-04-07T12:57:09.715522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:09.365542Z","time spent":"349.62924ms","remote":"127.0.0.1:55196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:57:09.924354Z","caller":"traceutil/trace.go:171","msg":"trace[388531382] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"202.8067ms","start":"2026-04-07T12:57:09.721509Z","end":"2026-04-07T12:57:09.924316Z","steps":["trace[388531382] 'process raft request' (duration: 202.657146ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:30.966729Z","caller":"traceutil/trace.go:171","msg":"trace[1790574129] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"114.089918ms","start":"2026-04-07T12:57:30.852615Z","end":"2026-04-07T12:57:30.966705Z","steps":["trace[1790574129] 'process raft request' (duration: 108.379442ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:30.967569Z","caller":"traceutil/trace.go:171","msg":"trace[1421526926] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"112.40678ms","start":"2026-04-07T12:57:30.855135Z","end":"2026-04-07T12:57:30.967541Z","steps":["trace[1421526926] 'process raft request' (duration: 112.212293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:36.670918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.132472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T12:57:36.671134Z","caller":"traceutil/trace.go:171","msg":"trace[1332547126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"166.39546ms","start":"2026-04-07T12:57:36.504721Z","end":"2026-04-07T12:57:36.671116Z","steps":["trace[1332547126] 'range keys from in-memory index tree' (duration: 162.62676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:36.910176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.224413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:57:36.91041Z","caller":"traceutil/trace.go:171","msg":"trace[644193912] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"261.774645ms","start":"2026-04-07T12:57:36.648609Z","end":"2026-04-07T12:57:36.910384Z","steps":["trace[644193912] 'process raft request' (duration: 126.253203ms)","trace[644193912] 'compare' (duration: 134.868023ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:36.91041Z","caller":"traceutil/trace.go:171","msg":"trace[1197385462] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"235.169691ms","start":"2026-04-07T12:57:36.675217Z","end":"2026-04-07T12:57:36.910387Z","steps":["trace[1197385462] 'process raft request' (duration: 235.075318ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:37.892936Z","caller":"traceutil/trace.go:171","msg":"trace[642227659] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"492.594788ms","start":"2026-04-07T12:57:37.400319Z","end":"2026-04-07T12:57:37.892913Z","steps":["trace[642227659] 'process raft request' (duration: 492.470865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:37.893069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:37.400287Z","time spent":"492.717752ms","remote":"127.0.0.1:55088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:57:37.9047Z","caller":"traceutil/trace.go:171","msg":"trace[1235526434] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"422.957382ms","start":"2026-04-07T12:57:37.481612Z","end":"2026-04-07T12:57:37.90457Z","steps":["trace[1235526434] 'read index received' (duration: 411.305504ms)","trace[1235526434] 'applied index is now lower than readState.Index' (duration: 11.650648ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:37.9056Z","caller":"traceutil/trace.go:171","msg":"trace[645574101] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"443.312584ms","start":"2026-04-07T12:57:37.46225Z","end":"2026-04-07T12:57:37.905563Z","steps":["trace[645574101] 'process raft request' (duration: 439.163634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:37.905724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:37.462225Z","time spent":"443.435218ms","remote":"127.0.0.1:55002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:57:37.905723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.082754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:57:37.905832Z","caller":"traceutil/trace.go:171","msg":"trace[1630584971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"424.221309ms","start":"2026-04-07T12:57:37.481582Z","end":"2026-04-07T12:57:37.905803Z","steps":["trace[1630584971] 'agreement among raft nodes before linearized reading' (duration: 423.130597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:37.905895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:37.481568Z","time spent":"424.312951ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T12:57:57.651701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.695524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jmfnb\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-07T12:57:57.651819Z","caller":"traceutil/trace.go:171","msg":"trace[1812501848] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jmfnb; range_end:; response_count:1; response_revision:793; }","duration":"157.821358ms","start":"2026-04-07T12:57:57.49397Z","end":"2026-04-07T12:57:57.651791Z","steps":["trace[1812501848] 'range keys from in-memory index tree' (duration: 157.370017ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:58:24.685661Z","caller":"traceutil/trace.go:171","msg":"trace[1191953017] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"165.891878ms","start":"2026-04-07T12:58:24.519747Z","end":"2026-04-07T12:58:24.685639Z","steps":["trace[1191953017] 'process raft request' (duration: 165.729854ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:58:46.908718Z","caller":"traceutil/trace.go:171","msg":"trace[1022272453] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"121.434263ms","start":"2026-04-07T12:58:46.787259Z","end":"2026-04-07T12:58:46.908693Z","steps":["trace[1022272453] 'process raft request' (duration: 121.257659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:10.546581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.51908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-07T12:59:10.54656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.530075ms","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-04-07T12:59:10.546671Z","caller":"traceutil/trace.go:171","msg":"trace[1373452293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1849; }","duration":"147.655143ms","start":"2026-04-07T12:59:10.398996Z","end":"2026-04-07T12:59:10.546651Z","steps":["trace[1373452293] 'range keys from in-memory index tree' (duration: 147.324095ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:10.546717Z","caller":"traceutil/trace.go:171","msg":"trace[475080529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1849; }","duration":"150.707529ms","start":"2026-04-07T12:59:10.395961Z","end":"2026-04-07T12:59:10.546668Z","steps":["trace[475080529] 'range keys from in-memory index tree' (duration: 150.34461ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:11.054357Z","caller":"traceutil/trace.go:171","msg":"trace[1350326863] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1920; }","duration":"115.326751ms","start":"2026-04-07T12:59:10.939004Z","end":"2026-04-07T12:59:11.054331Z","steps":["trace[1350326863] 'read index received' (duration: 115.263599ms)","trace[1350326863] 'applied index is now lower than readState.Index' (duration: 61.982µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:59:11.054508Z","caller":"traceutil/trace.go:171","msg":"trace[1655896653] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"192.762872ms","start":"2026-04-07T12:59:10.861701Z","end":"2026-04-07T12:59:11.054464Z","steps":["trace[1655896653] 'process raft request' (duration: 192.492656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:11.054819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.790832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a4148cd28a1d9e\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-07T12:59:11.054882Z","caller":"traceutil/trace.go:171","msg":"trace[984072061] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a4148cd28a1d9e; range_end:; response_count:1; response_revision:1852; }","duration":"115.905365ms","start":"2026-04-07T12:59:10.938961Z","end":"2026-04-07T12:59:11.054866Z","steps":["trace[984072061] 'agreement among raft nodes before linearized reading' (duration: 115.473154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:19.260523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.160708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:59:19.260634Z","caller":"traceutil/trace.go:171","msg":"trace[223880512] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"129.984035ms","start":"2026-04-07T12:59:19.130628Z","end":"2026-04-07T12:59:19.260612Z","steps":["trace[223880512] 'process raft request' (duration: 26.433616ms)","trace[223880512] 'compare' (duration: 102.585354ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:59:19.450418Z","caller":"traceutil/trace.go:171","msg":"trace[1010551134] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"181.78013ms","start":"2026-04-07T12:59:19.268597Z","end":"2026-04-07T12:59:19.450377Z","steps":["trace[1010551134] 'process raft request' (duration: 152.552135ms)","trace[1010551134] 'compare' (duration: 29.02965ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:59:23.612644Z","caller":"traceutil/trace.go:171","msg":"trace[914615814] linearizableReadLoop","detail":"{readStateIndex:2026; appliedIndex:2025; }","duration":"131.678737ms","start":"2026-04-07T12:59:23.480941Z","end":"2026-04-07T12:59:23.612619Z","steps":["trace[914615814] 'read index received' (duration: 131.550584ms)","trace[914615814] 'applied index is now lower than readState.Index' (duration: 127.083µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:59:23.612767Z","caller":"traceutil/trace.go:171","msg":"trace[2105402549] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"144.380912ms","start":"2026-04-07T12:59:23.468374Z","end":"2026-04-07T12:59:23.612754Z","steps":["trace[2105402549] 'process raft request' (duration: 144.113036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:23.612941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.989405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T12:59:23.613014Z","caller":"traceutil/trace.go:171","msg":"trace[679011329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1955; }","duration":"132.137098ms","start":"2026-04-07T12:59:23.48086Z","end":"2026-04-07T12:59:23.612997Z","steps":["trace[679011329] 'agreement among raft nodes before linearized reading' (duration: 131.960364ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:00.92337Z","caller":"traceutil/trace.go:171","msg":"trace[1827335094] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"102.211654ms","start":"2026-04-07T13:00:00.821115Z","end":"2026-04-07T13:00:00.923326Z","steps":["trace[1827335094] 'process raft request' (duration: 101.950057ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:01.032463Z","caller":"traceutil/trace.go:171","msg":"trace[98015459] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"104.094268ms","start":"2026-04-07T13:00:00.928314Z","end":"2026-04-07T13:00:01.032408Z","steps":["trace[98015459] 'process raft request' (duration: 46.216272ms)","trace[98015459] 'compare' (duration: 57.658761ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:00:01.297764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.905084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T13:00:01.297864Z","caller":"traceutil/trace.go:171","msg":"trace[2055879460] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"123.068219ms","start":"2026-04-07T13:00:01.174771Z","end":"2026-04-07T13:00:01.297839Z","steps":["trace[2055879460] 'range keys from in-memory index tree' (duration: 122.644797ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:24.441021Z","caller":"traceutil/trace.go:171","msg":"trace[748666319] linearizableReadLoop","detail":"{readStateIndex:4122; appliedIndex:4121; }","duration":"221.517558ms","start":"2026-04-07T13:04:24.219479Z","end":"2026-04-07T13:04:24.440997Z","steps":["trace[748666319] 'read index received' (duration: 221.342524ms)","trace[748666319] 'applied index is now lower than readState.Index' (duration: 173.484µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:04:24.441065Z","caller":"traceutil/trace.go:171","msg":"trace[1967791089] transaction","detail":"{read_only:false; response_revision:3975; number_of_response:1; }","duration":"233.433521ms","start":"2026-04-07T13:04:24.207602Z","end":"2026-04-07T13:04:24.441036Z","steps":["trace[1967791089] 'process raft request' (duration: 233.253517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:04:24.441271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.773045ms","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-04-07T13:04:24.44132Z","caller":"traceutil/trace.go:171","msg":"trace[96412020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3975; }","duration":"221.871547ms","start":"2026-04-07T13:04:24.219438Z","end":"2026-04-07T13:04:24.441309Z","steps":["trace[96412020] 'agreement among raft nodes before linearized reading' (duration: 221.692243ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:24.677022Z","caller":"traceutil/trace.go:171","msg":"trace[936121090] transaction","detail":"{read_only:false; response_revision:3976; number_of_response:1; }","duration":"238.233908ms","start":"2026-04-07T13:04:24.438757Z","end":"2026-04-07T13:04:24.676991Z","steps":["trace[936121090] 'process raft request' (duration: 182.513299ms)","trace[936121090] 'compare' (duration: 55.401291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:04:24.677185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.640715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:04:24.677257Z","caller":"traceutil/trace.go:171","msg":"trace[1010210702] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:3976; }","duration":"335.747338ms","start":"2026-04-07T13:04:24.341492Z","end":"2026-04-07T13:04:24.67724Z","steps":["trace[1010210702] 'agreement among raft nodes before linearized reading' (duration: 335.627115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:04:24.677337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:04:24.341481Z","time spent":"335.845151ms","remote":"127.0.0.1:48550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":5,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"info","ts":"2026-04-07T13:04:24.677118Z","caller":"traceutil/trace.go:171","msg":"trace[490214342] linearizableReadLoop","detail":"{readStateIndex:4123; appliedIndex:4122; }","duration":"235.983018ms","start":"2026-04-07T13:04:24.441104Z","end":"2026-04-07T13:04:24.677087Z","steps":["trace[490214342] 'read index received' (duration: 180.187418ms)","trace[490214342] 'applied index is now lower than readState.Index' (duration: 55.79313ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:04:24.677448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.984603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T13:04:24.677527Z","caller":"traceutil/trace.go:171","msg":"trace[983693769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3976; }","duration":"380.093875ms","start":"2026-04-07T13:04:24.29742Z","end":"2026-04-07T13:04:24.677514Z","steps":["trace[983693769] 'agreement among raft nodes before linearized reading' (duration: 379.812418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:04:24.677619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:04:24.297406Z","time spent":"380.198079ms","remote":"127.0.0.1:55196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T13:04:24.677788Z","caller":"traceutil/trace.go:171","msg":"trace[1407919134] transaction","detail":"{read_only:false; response_revision:3977; number_of_response:1; }","duration":"233.092723ms","start":"2026-04-07T13:04:24.444681Z","end":"2026-04-07T13:04:24.677773Z","steps":["trace[1407919134] 'process raft request' (duration: 232.278281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:04:24.678303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.808751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-07T13:04:24.67837Z","caller":"traceutil/trace.go:171","msg":"trace[1054118745] range","detail":"{range_begin:/registry/masterleases/199.19.213.99; range_end:; response_count:1; response_revision:3977; }","duration":"192.943694ms","start":"2026-04-07T13:04:24.485411Z","end":"2026-04-07T13:04:24.678355Z","steps":["trace[1054118745] 'agreement among raft nodes before linearized reading' (duration: 192.292156ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:26.837452Z","caller":"traceutil/trace.go:171","msg":"trace[260273865] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"126.172261ms","start":"2026-04-07T13:05:26.711257Z","end":"2026-04-07T13:05:26.837429Z","steps":["trace[260273865] 'process raft request' (duration: 126.016218ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:26.837515Z","caller":"traceutil/trace.go:171","msg":"trace[2053541239] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"128.750786ms","start":"2026-04-07T13:05:26.708732Z","end":"2026-04-07T13:05:26.837483Z","steps":["trace[2053541239] 'process raft request' (duration: 128.404727ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:26.837595Z","caller":"traceutil/trace.go:171","msg":"trace[2043267069] linearizableReadLoop","detail":"{readStateIndex:4957; appliedIndex:4955; }","duration":"126.5061ms","start":"2026-04-07T13:05:26.711055Z","end":"2026-04-07T13:05:26.837562Z","steps":["trace[2043267069] 'read index received' (duration: 13.281461ms)","trace[2043267069] 'applied index is now lower than readState.Index' (duration: 113.222709ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:05:26.83764Z","caller":"traceutil/trace.go:171","msg":"trace[245775497] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"121.894054ms","start":"2026-04-07T13:05:26.715729Z","end":"2026-04-07T13:05:26.837623Z","steps":["trace[245775497] 'process raft request' (duration: 121.592197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:26.837818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.745676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-07T13:05:26.837885Z","caller":"traceutil/trace.go:171","msg":"trace[1311994259] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"117.146666ms","start":"2026-04-07T13:05:26.72073Z","end":"2026-04-07T13:05:26.837877Z","steps":["trace[1311994259] 'process raft request' (duration: 116.644363ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:26.837866Z","caller":"traceutil/trace.go:171","msg":"trace[993728742] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4780; }","duration":"126.830589ms","start":"2026-04-07T13:05:26.711021Z","end":"2026-04-07T13:05:26.837852Z","steps":["trace[993728742] 'agreement among raft nodes before linearized reading' (duration: 126.655045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:36.486112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.593737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eb6529e7-2946-4ef9-824b-f35161af442e\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-07T13:05:36.486205Z","caller":"traceutil/trace.go:171","msg":"trace[276497001] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eb6529e7-2946-4ef9-824b-f35161af442e; range_end:; response_count:1; response_revision:5084; }","duration":"123.772201ms","start":"2026-04-07T13:05:36.362415Z","end":"2026-04-07T13:05:36.486187Z","steps":["trace[276497001] 'range keys from in-memory index tree' (duration: 123.450583ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:36.853221Z","caller":"traceutil/trace.go:171","msg":"trace[414259974] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"104.879742ms","start":"2026-04-07T13:05:36.748313Z","end":"2026-04-07T13:05:36.853193Z","steps":["trace[414259974] 'process raft request' (duration: 61.988884ms)","trace[414259974] 'compare' (duration: 42.711494ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:05:53.167107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.875922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T13:05:53.167394Z","caller":"traceutil/trace.go:171","msg":"trace[1468066146] linearizableReadLoop","detail":"{readStateIndex:5638; appliedIndex:5637; }","duration":"264.430763ms","start":"2026-04-07T13:05:52.902934Z","end":"2026-04-07T13:05:53.167365Z","steps":["trace[1468066146] 'read index received' (duration: 118.218783ms)","trace[1468066146] 'applied index is now lower than readState.Index' (duration: 146.20894ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:05:53.167383Z","caller":"traceutil/trace.go:171","msg":"trace[1302592660] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"264.949816ms","start":"2026-04-07T13:05:52.902399Z","end":"2026-04-07T13:05:53.167349Z","steps":["trace[1302592660] 'process raft request' (duration: 118.732635ms)","trace[1302592660] 'compare' (duration: 145.644557ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:05:53.167457Z","caller":"traceutil/trace.go:171","msg":"trace[9885709] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"261.216174ms","start":"2026-04-07T13:05:52.906217Z","end":"2026-04-07T13:05:53.167433Z","steps":["trace[9885709] 'process raft request' (duration: 261.04689ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:53.167769Z","caller":"traceutil/trace.go:171","msg":"trace[1663009566] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"254.395453ms","start":"2026-04-07T13:05:52.913302Z","end":"2026-04-07T13:05:53.167697Z","steps":["trace[1663009566] 'process raft request' (duration: 254.060315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.16783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.866274ms","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-04-07T13:05:53.167912Z","caller":"traceutil/trace.go:171","msg":"trace[1328823366] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5441; }","duration":"264.961927ms","start":"2026-04-07T13:05:52.902928Z","end":"2026-04-07T13:05:53.16789Z","steps":["trace[1328823366] 'agreement among raft nodes before linearized reading' (duration: 264.531745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.171323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.88282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1bd17fba-dfca-4b27-be8c-724c0d55281d\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-07T13:05:53.171394Z","caller":"traceutil/trace.go:171","msg":"trace[1318350139] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1bd17fba-dfca-4b27-be8c-724c0d55281d; range_end:; response_count:1; response_revision:5443; }","duration":"223.004683ms","start":"2026-04-07T13:05:52.948373Z","end":"2026-04-07T13:05:53.171377Z","steps":["trace[1318350139] 'agreement among raft nodes before linearized reading' (duration: 222.794527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.171429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.226028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:4788"} {"level":"info","ts":"2026-04-07T13:05:53.171486Z","caller":"traceutil/trace.go:171","msg":"trace[689689793] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5443; }","duration":"268.344001ms","start":"2026-04-07T13:05:52.903128Z","end":"2026-04-07T13:05:53.171472Z","steps":["trace[689689793] 'agreement among raft nodes before linearized reading' (duration: 268.007383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.171582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.337567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-bf2qw\" ","response":"range_response_count:1 size:2596"} {"level":"info","ts":"2026-04-07T13:05:53.171617Z","caller":"traceutil/trace.go:171","msg":"trace[1394196775] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-bf2qw; range_end:; response_count:1; response_revision:5443; }","duration":"265.428168ms","start":"2026-04-07T13:05:52.906179Z","end":"2026-04-07T13:05:53.171607Z","steps":["trace[1394196775] 'agreement among raft nodes before linearized reading' (duration: 265.017638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.172044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.531532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210982"} {"level":"info","ts":"2026-04-07T13:05:53.172155Z","caller":"traceutil/trace.go:171","msg":"trace[1435081398] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5443; }","duration":"159.695316ms","start":"2026-04-07T13:05:53.012439Z","end":"2026-04-07T13:05:53.172135Z","steps":["trace[1435081398] 'agreement among raft nodes before linearized reading' (duration: 158.762803ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:53.786941Z","caller":"traceutil/trace.go:171","msg":"trace[181012398] linearizableReadLoop","detail":"{readStateIndex:5647; appliedIndex:5646; }","duration":"521.443523ms","start":"2026-04-07T13:05:53.265462Z","end":"2026-04-07T13:05:53.786905Z","steps":["trace[181012398] 'read index received' (duration: 475.590611ms)","trace[181012398] 'applied index is now lower than readState.Index' (duration: 45.851061ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:05:53.786956Z","caller":"traceutil/trace.go:171","msg":"trace[1552291002] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"523.832462ms","start":"2026-04-07T13:05:53.263091Z","end":"2026-04-07T13:05:53.786924Z","steps":["trace[1552291002] 'process raft request' (duration: 477.819616ms)","trace[1552291002] 'compare' (duration: 45.651706ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:05:53.78714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.653358ms","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":"warn","ts":"2026-04-07T13:05:53.787167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:05:53.263052Z","time spent":"524.024556ms","remote":"127.0.0.1:48494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2304,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:05:53.787209Z","caller":"traceutil/trace.go:171","msg":"trace[880117685] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5448; }","duration":"521.74484ms","start":"2026-04-07T13:05:53.265446Z","end":"2026-04-07T13:05:53.787191Z","steps":["trace[880117685] 'agreement among raft nodes before linearized reading' (duration: 521.552886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.787264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:05:53.265439Z","time spent":"521.815802ms","remote":"127.0.0.1:55002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":730,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"info","ts":"2026-04-07T13:05:53.80127Z","caller":"traceutil/trace.go:171","msg":"trace[1655120812] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"377.357365ms","start":"2026-04-07T13:05:53.423891Z","end":"2026-04-07T13:05:53.801248Z","steps":["trace[1655120812] 'process raft request' (duration: 377.184471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.801391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:05:53.423864Z","time spent":"377.465328ms","remote":"127.0.0.1:55196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:05:53.801648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.309442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T13:05:53.801724Z","caller":"traceutil/trace.go:171","msg":"trace[1207939361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5449; }","duration":"299.432605ms","start":"2026-04-07T13:05:53.502273Z","end":"2026-04-07T13:05:53.801705Z","steps":["trace[1207939361] 'agreement among raft nodes before linearized reading' (duration: 299.214709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.801797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.417135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k4bkf\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T13:05:53.80187Z","caller":"traceutil/trace.go:171","msg":"trace[377120182] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k4bkf; range_end:; response_count:1; response_revision:5449; }","duration":"293.503147ms","start":"2026-04-07T13:05:53.508353Z","end":"2026-04-07T13:05:53.801856Z","steps":["trace[377120182] 'agreement among raft nodes before linearized reading' (duration: 293.335623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.801958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.142387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T13:05:53.80202Z","caller":"traceutil/trace.go:171","msg":"trace[363749486] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5449; }","duration":"295.234351ms","start":"2026-04-07T13:05:53.506767Z","end":"2026-04-07T13:05:53.802001Z","steps":["trace[363749486] 'agreement among raft nodes before linearized reading' (duration: 295.099847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.802004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.78914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-07T13:05:53.802037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.57435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-04-07T13:05:53.802085Z","caller":"traceutil/trace.go:171","msg":"trace[671019212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5449; }","duration":"245.939103ms","start":"2026-04-07T13:05:53.556127Z","end":"2026-04-07T13:05:53.802066Z","steps":["trace[671019212] 'agreement among raft nodes before linearized reading' (duration: 245.724678ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:53.802118Z","caller":"traceutil/trace.go:171","msg":"trace[1839056160] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5449; }","duration":"524.675872ms","start":"2026-04-07T13:05:53.277418Z","end":"2026-04-07T13:05:53.802094Z","steps":["trace[1839056160] 'agreement among raft nodes before linearized reading' (duration: 524.470787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.80181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.033026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"warn","ts":"2026-04-07T13:05:53.802171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:05:53.2774Z","time spent":"524.756175ms","remote":"127.0.0.1:55002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3587,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-04-07T13:05:53.802184Z","caller":"traceutil/trace.go:171","msg":"trace[27277531] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5449; }","duration":"440.432575ms","start":"2026-04-07T13:05:53.36173Z","end":"2026-04-07T13:05:53.802162Z","steps":["trace[27277531] 'agreement among raft nodes before linearized reading' (duration: 439.882061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:53.802268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:05:53.361704Z","time spent":"440.538478ms","remote":"127.0.0.1:50400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":413152,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-04-07T13:05:53.802288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.208829ms","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-04-07T13:05:53.802587Z","caller":"traceutil/trace.go:171","msg":"trace[160373999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5449; }","duration":"295.63129ms","start":"2026-04-07T13:05:53.506935Z","end":"2026-04-07T13:05:53.802566Z","steps":["trace[160373999] 'agreement among raft nodes before linearized reading' (duration: 294.874361ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:54.022493Z","caller":"traceutil/trace.go:171","msg":"trace[228729497] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"214.937342ms","start":"2026-04-07T13:05:53.807525Z","end":"2026-04-07T13:05:54.022462Z","steps":["trace[228729497] 'process raft request' (duration: 201.034495ms)","trace[228729497] 'compare' (duration: 13.769644ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:05:54.054031Z","caller":"traceutil/trace.go:171","msg":"trace[2108186050] linearizableReadLoop","detail":"{readStateIndex:5652; appliedIndex:5648; }","duration":"243.283587ms","start":"2026-04-07T13:05:53.810724Z","end":"2026-04-07T13:05:54.054008Z","steps":["trace[2108186050] 'read index received' (duration: 197.911707ms)","trace[2108186050] 'applied index is now lower than readState.Index' (duration: 45.37098ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:05:54.054069Z","caller":"traceutil/trace.go:171","msg":"trace[1432630009] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"246.154369ms","start":"2026-04-07T13:05:53.807897Z","end":"2026-04-07T13:05:54.054051Z","steps":["trace[1432630009] 'process raft request' (duration: 246.050667ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:54.054114Z","caller":"traceutil/trace.go:171","msg":"trace[1926266391] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"245.936233ms","start":"2026-04-07T13:05:53.80817Z","end":"2026-04-07T13:05:54.054106Z","steps":["trace[1926266391] 'process raft request' (duration: 245.80578ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:54.054141Z","caller":"traceutil/trace.go:171","msg":"trace[1724787887] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"246.326823ms","start":"2026-04-07T13:05:53.80778Z","end":"2026-04-07T13:05:54.054107Z","steps":["trace[1724787887] 'process raft request' (duration: 246.058877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:05:54.054246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.499132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"warn","ts":"2026-04-07T13:05:54.054643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.529508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110620"} {"level":"warn","ts":"2026-04-07T13:05:54.05457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.59589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-07T13:05:54.054683Z","caller":"traceutil/trace.go:171","msg":"trace[24811021] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5453; }","duration":"240.58562ms","start":"2026-04-07T13:05:53.814086Z","end":"2026-04-07T13:05:54.054671Z","steps":["trace[24811021] 'agreement among raft nodes before linearized reading' (duration: 240.358975ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:54.054712Z","caller":"traceutil/trace.go:171","msg":"trace[876314184] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5453; }","duration":"240.772625ms","start":"2026-04-07T13:05:53.813929Z","end":"2026-04-07T13:05:54.054702Z","steps":["trace[876314184] 'agreement among raft nodes before linearized reading' (duration: 240.504949ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:54.054718Z","caller":"traceutil/trace.go:171","msg":"trace[592260955] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5453; }","duration":"243.967734ms","start":"2026-04-07T13:05:53.810694Z","end":"2026-04-07T13:05:54.054662Z","steps":["trace[592260955] 'agreement among raft nodes before linearized reading' (duration: 243.422461ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:56.150031Z","caller":"traceutil/trace.go:171","msg":"trace[1209904855] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"118.202873ms","start":"2026-04-07T13:05:56.031782Z","end":"2026-04-07T13:05:56.149985Z","steps":["trace[1209904855] 'process raft request' (duration: 117.990067ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:07:01.328619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3019} {"level":"info","ts":"2026-04-07T13:07:01.409896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3019,"took":"79.840374ms","hash":912267186,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":31379456,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T13:07:01.409949Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":912267186,"revision":3019,"compact-revision":-1} {"level":"info","ts":"2026-04-07T13:11:59.170513Z","caller":"traceutil/trace.go:171","msg":"trace[454059223] transaction","detail":"{read_only:false; response_revision:8284; number_of_response:1; }","duration":"228.621847ms","start":"2026-04-07T13:11:58.94186Z","end":"2026-04-07T13:11:59.170482Z","steps":["trace[454059223] 'process raft request' (duration: 139.655719ms)","trace[454059223] 'compare' (duration: 88.764363ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:12:01.336622Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6093} {"level":"info","ts":"2026-04-07T13:12:01.442237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6093,"took":"103.594336ms","hash":3861247327,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":23707648,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-07T13:12:01.442293Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3861247327,"revision":6093,"compact-revision":3019} {"level":"warn","ts":"2026-04-07T13:12:10.03075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.94911ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T13:12:10.840906Z","caller":"traceutil/trace.go:171","msg":"trace[1494121436] transaction","detail":"{read_only:false; response_revision:8342; number_of_response:1; }","duration":"255.511751ms","start":"2026-04-07T13:12:10.585364Z","end":"2026-04-07T13:12:10.840876Z","steps":["trace[1494121436] 'process raft request' (duration: 255.285105ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:10.870146Z","caller":"traceutil/trace.go:171","msg":"trace[1380306442] transaction","detail":"{read_only:false; response_revision:8343; number_of_response:1; }","duration":"284.674882ms","start":"2026-04-07T13:12:10.585448Z","end":"2026-04-07T13:12:10.870123Z","steps":["trace[1380306442] 'process raft request' (duration: 284.467757ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:10.870292Z","caller":"traceutil/trace.go:171","msg":"trace[1444440231] transaction","detail":"{read_only:false; response_revision:8344; number_of_response:1; }","duration":"277.585913ms","start":"2026-04-07T13:12:10.592682Z","end":"2026-04-07T13:12:10.870268Z","steps":["trace[1444440231] 'process raft request' (duration: 277.400329ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:12.181705Z","caller":"traceutil/trace.go:171","msg":"trace[25821217] linearizableReadLoop","detail":"{readStateIndex:8639; appliedIndex:8638; }","duration":"105.005961ms","start":"2026-04-07T13:12:12.076668Z","end":"2026-04-07T13:12:12.181674Z","steps":["trace[25821217] 'read index received' (duration: 104.766165ms)","trace[25821217] 'applied index is now lower than readState.Index' (duration: 238.476µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:12:12.181812Z","caller":"traceutil/trace.go:171","msg":"trace[838921461] transaction","detail":"{read_only:false; response_revision:8348; number_of_response:1; }","duration":"118.842426ms","start":"2026-04-07T13:12:12.062942Z","end":"2026-04-07T13:12:12.181785Z","steps":["trace[838921461] 'process raft request' (duration: 118.58041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:12.181948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.260748ms","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-04-07T13:12:12.182021Z","caller":"traceutil/trace.go:171","msg":"trace[2125111892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8348; }","duration":"105.419732ms","start":"2026-04-07T13:12:12.076579Z","end":"2026-04-07T13:12:12.181999Z","steps":["trace[2125111892] 'agreement among raft nodes before linearized reading' (duration: 105.215557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:12.18207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.475137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-07T13:12:12.182109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.049952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k4bkf\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T13:12:12.182123Z","caller":"traceutil/trace.go:171","msg":"trace[1308592071] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8348; }","duration":"104.659211ms","start":"2026-04-07T13:12:12.077447Z","end":"2026-04-07T13:12:12.182106Z","steps":["trace[1308592071] 'agreement among raft nodes before linearized reading' (duration: 104.405855ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:12.182151Z","caller":"traceutil/trace.go:171","msg":"trace[647192861] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k4bkf; range_end:; response_count:1; response_revision:8348; }","duration":"101.129213ms","start":"2026-04-07T13:12:12.081008Z","end":"2026-04-07T13:12:12.182138Z","steps":["trace[647192861] 'agreement among raft nodes before linearized reading' (duration: 100.974819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:40.338046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.030852ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T13:12:40.338174Z","caller":"traceutil/trace.go:171","msg":"trace[1574481275] linearizableReadLoop","detail":"{readStateIndex:8851; appliedIndex:8850; }","duration":"217.93383ms","start":"2026-04-07T13:12:40.120219Z","end":"2026-04-07T13:12:40.338153Z","steps":["trace[1574481275] 'read index received' (duration: 5.737384ms)","trace[1574481275] 'applied index is now lower than readState.Index' (duration: 212.194806ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:12:40.338376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.142475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:12:40.338496Z","caller":"traceutil/trace.go:171","msg":"trace[535508162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8552; }","duration":"218.270038ms","start":"2026-04-07T13:12:40.120186Z","end":"2026-04-07T13:12:40.338456Z","steps":["trace[535508162] 'agreement among raft nodes before linearized reading' (duration: 218.120704ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:52.416141Z","caller":"traceutil/trace.go:171","msg":"trace[2016155920] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8659; }","duration":"117.269898ms","start":"2026-04-07T13:12:52.298775Z","end":"2026-04-07T13:12:52.416045Z","steps":["trace[2016155920] 'process raft request' (duration: 72.192979ms)","trace[2016155920] 'compare' (duration: 44.866564ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:12:52.483939Z","caller":"traceutil/trace.go:171","msg":"trace[1965960999] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"182.980984ms","start":"2026-04-07T13:12:52.300932Z","end":"2026-04-07T13:12:52.483913Z","steps":["trace[1965960999] 'process raft request' (duration: 182.892322ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:52.595507Z","caller":"traceutil/trace.go:171","msg":"trace[1051823192] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"104.724953ms","start":"2026-04-07T13:12:52.490759Z","end":"2026-04-07T13:12:52.595484Z","steps":["trace[1051823192] 'process raft request' (duration: 104.369964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:52.595964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.298897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T13:12:52.596021Z","caller":"traceutil/trace.go:171","msg":"trace[105451767] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8662; }","duration":"105.38575ms","start":"2026-04-07T13:12:52.490618Z","end":"2026-04-07T13:12:52.596004Z","steps":["trace[105451767] 'agreement among raft nodes before linearized reading' (duration: 105.235856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:52.596217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.461312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-58kh7\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-04-07T13:12:52.596255Z","caller":"traceutil/trace.go:171","msg":"trace[42707707] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-58kh7; range_end:; response_count:1; response_revision:8662; }","duration":"105.549385ms","start":"2026-04-07T13:12:52.490696Z","end":"2026-04-07T13:12:52.596245Z","steps":["trace[42707707] 'agreement among raft nodes before linearized reading' (duration: 105.452862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:52.596314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.623766ms","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-04-07T13:12:52.596374Z","caller":"traceutil/trace.go:171","msg":"trace[1050377521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8662; }","duration":"105.701769ms","start":"2026-04-07T13:12:52.490655Z","end":"2026-04-07T13:12:52.596357Z","steps":["trace[1050377521] 'agreement among raft nodes before linearized reading' (duration: 105.575215ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:53.190626Z","caller":"traceutil/trace.go:171","msg":"trace[1684940121] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"190.113062ms","start":"2026-04-07T13:12:53.00048Z","end":"2026-04-07T13:12:53.190593Z","steps":["trace[1684940121] 'process raft request' (duration: 189.990479ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:53.228078Z","caller":"traceutil/trace.go:171","msg":"trace[332043503] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"188.167134ms","start":"2026-04-07T13:12:53.039891Z","end":"2026-04-07T13:12:53.228059Z","steps":["trace[332043503] 'process raft request' (duration: 187.907307ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:53.228269Z","caller":"traceutil/trace.go:171","msg":"trace[603282223] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"185.851656ms","start":"2026-04-07T13:12:53.04241Z","end":"2026-04-07T13:12:53.228262Z","steps":["trace[603282223] 'process raft request' (duration: 185.61664ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:53.228273Z","caller":"traceutil/trace.go:171","msg":"trace[2140542886] linearizableReadLoop","detail":"{readStateIndex:8973; appliedIndex:8970; }","duration":"107.052781ms","start":"2026-04-07T13:12:53.121184Z","end":"2026-04-07T13:12:53.228236Z","steps":["trace[2140542886] 'read index received' (duration: 69.260815ms)","trace[2140542886] 'applied index is now lower than readState.Index' (duration: 37.790686ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:12:53.228408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.205436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:12:53.22845Z","caller":"traceutil/trace.go:171","msg":"trace[162228906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8669; }","duration":"107.299788ms","start":"2026-04-07T13:12:53.12114Z","end":"2026-04-07T13:12:53.22844Z","steps":["trace[162228906] 'agreement among raft nodes before linearized reading' (duration: 107.216126ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:53.357551Z","caller":"traceutil/trace.go:171","msg":"trace[730583201] transaction","detail":"{read_only:false; response_revision:8670; number_of_response:1; }","duration":"127.113874ms","start":"2026-04-07T13:12:53.230415Z","end":"2026-04-07T13:12:53.357529Z","steps":["trace[730583201] 'process raft request' (duration: 67.715326ms)","trace[730583201] 'compare' (duration: 59.295615ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:12:53.370485Z","caller":"traceutil/trace.go:171","msg":"trace[1498382251] transaction","detail":"{read_only:false; response_revision:8671; number_of_response:1; }","duration":"127.966116ms","start":"2026-04-07T13:12:53.242482Z","end":"2026-04-07T13:12:53.370448Z","steps":["trace[1498382251] 'process raft request' (duration: 127.666358ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:50.096191Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002555.wal"} {"level":"info","ts":"2026-04-07T13:14:43.141904Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4da37caf00b87dfe","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T13:14:43.148914Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T13:14:43.149054Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T13:17:01.344181Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8295} {"level":"info","ts":"2026-04-07T13:17:01.421322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8295,"took":"75.389982ms","hash":2502426923,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":25608192,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-07T13:17:01.421406Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2502426923,"revision":8295,"compact-revision":6093} {"level":"info","ts":"2026-04-07T13:22:01.350517Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10697} {"level":"info","ts":"2026-04-07T13:22:01.432478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10697,"took":"78.948938ms","hash":4018893895,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":20996096,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-07T13:22:01.432586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4018893895,"revision":10697,"compact-revision":8295}