{"level":"warn","ts":"2026-04-13T01:29:27.273868Z","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-13T01:29:27.273994Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.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.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.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-04-13T01:29:27.274089Z","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-13T01:29:27.274108Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-13T01:29:27.274158Z","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-13T01:29:27.274975Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-04-13T01:29:27.275106Z","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.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.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-04-13T01:29:27.280981Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.381954ms"} {"level":"info","ts":"2026-04-13T01:29:27.289981Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-04-13T01:29:27.290119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T01:29:27.290152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-04-13T01:29:27.290169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T01:29:27.290185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-04-13T01:29:27.290238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-04-13T01:29:27.300813Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T01:29:27.302758Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T01:29:27.303911Z","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-13T01:29:27.30599Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T01:29:27.306176Z","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-13T01:29:27.306317Z","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-13T01:29:27.306335Z","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-13T01:29:27.306709Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T01:29:27.307054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-04-13T01:29:27.30722Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-13T01:29:27.311331Z","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-13T01:29:27.311414Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-13T01:29:27.311488Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-13T01:29:27.311868Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T01:29:27.311947Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T01:29:27.39093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T01:29:27.391054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T01:29:27.391099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-04-13T01:29:27.391132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-04-13T01:29:27.391151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-13T01:29:27.391176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-04-13T01:29:27.391211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-13T01:29:27.39216Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T01:29:27.393029Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T01:29:27.393026Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T01:29:27.393132Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T01:29:27.393302Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T01:29:27.393404Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T01:29:27.39346Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T01:29:27.39347Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T01:29:27.393532Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T01:29:27.395425Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T01:29:27.397379Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-04-13T01:29:43.426179Z","caller":"traceutil/trace.go:171","msg":"trace[420528731] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"194.740738ms","start":"2026-04-13T01:29:43.231413Z","end":"2026-04-13T01:29:43.426154Z","steps":["trace[420528731] 'process raft request' (duration: 150.150861ms)","trace[420528731] 'compare' (duration: 44.335092ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:29:43.790229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.651416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T01:29:43.790398Z","caller":"traceutil/trace.go:171","msg":"trace[1418084430] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"341.766087ms","start":"2026-04-13T01:29:43.448614Z","end":"2026-04-13T01:29:43.79038Z","steps":["trace[1418084430] 'read index received' (duration: 133.147788ms)","trace[1418084430] 'applied index is now lower than readState.Index' (duration: 208.616809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:29:43.790483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.88172ms","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-04-13T01:29:43.790458Z","caller":"traceutil/trace.go:171","msg":"trace[1132785688] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"359.721311ms","start":"2026-04-13T01:29:43.430681Z","end":"2026-04-13T01:29:43.790402Z","steps":["trace[1132785688] 'process raft request' (duration: 151.194674ms)","trace[1132785688] 'compare' (duration: 207.489643ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:29:43.79053Z","caller":"traceutil/trace.go:171","msg":"trace[1019708843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:370; }","duration":"341.986882ms","start":"2026-04-13T01:29:43.448531Z","end":"2026-04-13T01:29:43.790518Z","steps":["trace[1019708843] 'agreement among raft nodes before linearized reading' (duration: 341.89592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:29:43.790568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:29:43.448502Z","time spent":"342.057424ms","remote":"127.0.0.1:49394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T01:29:43.790642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:29:43.430663Z","time spent":"359.875325ms","remote":"127.0.0.1:49394","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-13T01:30:00.764043Z","caller":"traceutil/trace.go:171","msg":"trace[383593654] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"152.407861ms","start":"2026-04-13T01:30:00.611596Z","end":"2026-04-13T01:30:00.764004Z","steps":["trace[383593654] 'process raft request' (duration: 151.115102ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:30:03.931307Z","caller":"traceutil/trace.go:171","msg":"trace[1942965692] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"230.484169ms","start":"2026-04-13T01:30:03.7008Z","end":"2026-04-13T01:30:03.931284Z","steps":["trace[1942965692] 'process raft request' (duration: 229.933576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:30:04.475766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.926857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T01:30:04.476035Z","caller":"traceutil/trace.go:171","msg":"trace[970112724] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"155.662095ms","start":"2026-04-13T01:30:04.320349Z","end":"2026-04-13T01:30:04.476011Z","steps":["trace[970112724] 'read index received' (duration: 52.571µs)","trace[970112724] 'applied index is now lower than readState.Index' (duration: 155.607944ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:30:04.47612Z","caller":"traceutil/trace.go:171","msg":"trace[1916994245] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"485.672025ms","start":"2026-04-13T01:30:03.990418Z","end":"2026-04-13T01:30:04.47609Z","steps":["trace[1916994245] 'process raft request' (duration: 485.494351ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:30:04.476153Z","caller":"traceutil/trace.go:171","msg":"trace[478301555] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"528.267633ms","start":"2026-04-13T01:30:03.947855Z","end":"2026-04-13T01:30:04.476123Z","steps":["trace[478301555] 'process raft request' (duration: 293.856735ms)","trace[478301555] 'compare' (duration: 233.688122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:30:04.476279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:30:03.990402Z","time spent":"485.804757ms","remote":"127.0.0.1:49586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T01:30:04.476293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:30:03.94783Z","time spent":"528.388996ms","remote":"127.0.0.1:49336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T01:30:04.476303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.946021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T01:30:04.47635Z","caller":"traceutil/trace.go:171","msg":"trace[2139476328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"156.027343ms","start":"2026-04-13T01:30:04.320307Z","end":"2026-04-13T01:30:04.476335Z","steps":["trace[2139476328] 'agreement among raft nodes before linearized reading' (duration: 155.833059ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:30:08.898385Z","caller":"traceutil/trace.go:171","msg":"trace[1220647345] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"141.736262ms","start":"2026-04-13T01:30:08.756618Z","end":"2026-04-13T01:30:08.898354Z","steps":["trace[1220647345] 'read index received' (duration: 141.405784ms)","trace[1220647345] 'applied index is now lower than readState.Index' (duration: 329.538µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:30:08.898481Z","caller":"traceutil/trace.go:171","msg":"trace[1037934787] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"150.521269ms","start":"2026-04-13T01:30:08.747932Z","end":"2026-04-13T01:30:08.898453Z","steps":["trace[1037934787] 'process raft request' (duration: 150.251273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:30:08.898605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.968427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-13T01:30:08.89867Z","caller":"traceutil/trace.go:171","msg":"trace[1947251601] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:650; }","duration":"142.068179ms","start":"2026-04-13T01:30:08.756569Z","end":"2026-04-13T01:30:08.898637Z","steps":["trace[1947251601] 'agreement among raft nodes before linearized reading' (duration: 141.900946ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:30:09.11598Z","caller":"traceutil/trace.go:171","msg":"trace[1664062920] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"105.621458ms","start":"2026-04-13T01:30:09.010331Z","end":"2026-04-13T01:30:09.115953Z","steps":["trace[1664062920] 'process raft request' (duration: 104.337929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:30:20.241568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.140112ms","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-04-13T01:30:20.241679Z","caller":"traceutil/trace.go:171","msg":"trace[1745237016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:739; }","duration":"135.282825ms","start":"2026-04-13T01:30:20.106371Z","end":"2026-04-13T01:30:20.241654Z","steps":["trace[1745237016] 'range keys from in-memory index tree' (duration: 134.975069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:30:20.241754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.340973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9zz2k\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-13T01:30:20.241956Z","caller":"traceutil/trace.go:171","msg":"trace[1680193713] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9zz2k; range_end:; response_count:1; response_revision:739; }","duration":"225.61211ms","start":"2026-04-13T01:30:20.016325Z","end":"2026-04-13T01:30:20.241937Z","steps":["trace[1680193713] 'range keys from in-memory index tree' (duration: 225.148169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:30:20.242215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.717503ms","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-13T01:30:20.242277Z","caller":"traceutil/trace.go:171","msg":"trace[1195313159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:739; }","duration":"142.835135ms","start":"2026-04-13T01:30:20.099427Z","end":"2026-04-13T01:30:20.242262Z","steps":["trace[1195313159] 'range keys from in-memory index tree' (duration: 142.250972ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:40.616311Z","caller":"traceutil/trace.go:171","msg":"trace[1599829876] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1903; }","duration":"312.804513ms","start":"2026-04-13T01:31:40.303485Z","end":"2026-04-13T01:31:40.616289Z","steps":["trace[1599829876] 'read index received' (duration: 312.606018ms)","trace[1599829876] 'applied index is now lower than readState.Index' (duration: 197.855µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:31:40.616406Z","caller":"traceutil/trace.go:171","msg":"trace[1758017342] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"361.029868ms","start":"2026-04-13T01:31:40.255337Z","end":"2026-04-13T01:31:40.616367Z","steps":["trace[1758017342] 'process raft request' (duration: 360.817914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:40.616474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.473535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:31:40.616506Z","caller":"traceutil/trace.go:171","msg":"trace[552342012] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1832; }","duration":"308.581437ms","start":"2026-04-13T01:31:40.307914Z","end":"2026-04-13T01:31:40.616496Z","steps":["trace[552342012] 'agreement among raft nodes before linearized reading' (duration: 308.512096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:40.616542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:31:40.307885Z","time spent":"308.649899ms","remote":"127.0.0.1:55472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-13T01:31:40.616586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:31:40.255305Z","time spent":"361.182662ms","remote":"127.0.0.1:49394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T01:31:40.616818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.090659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0654c4ee-a460-4db3-8889-1283dfb6dcaa\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-13T01:31:40.616813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.027708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T01:31:40.61678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.261253ms","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-13T01:31:40.616925Z","caller":"traceutil/trace.go:171","msg":"trace[2106939891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1832; }","duration":"230.186772ms","start":"2026-04-13T01:31:40.386714Z","end":"2026-04-13T01:31:40.616901Z","steps":["trace[2106939891] 'agreement among raft nodes before linearized reading' (duration: 229.976168ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:40.616946Z","caller":"traceutil/trace.go:171","msg":"trace[583742326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1832; }","duration":"313.498568ms","start":"2026-04-13T01:31:40.30342Z","end":"2026-04-13T01:31:40.616918Z","steps":["trace[583742326] 'agreement among raft nodes before linearized reading' (duration: 312.976016ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:40.616862Z","caller":"traceutil/trace.go:171","msg":"trace[1188289355] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0654c4ee-a460-4db3-8889-1283dfb6dcaa; range_end:; response_count:1; response_revision:1832; }","duration":"170.183211ms","start":"2026-04-13T01:31:40.446667Z","end":"2026-04-13T01:31:40.61685Z","steps":["trace[1188289355] 'agreement among raft nodes before linearized reading' (duration: 170.055498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:40.617003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:31:40.303382Z","time spent":"313.60769ms","remote":"127.0.0.1:49394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T01:31:46.923315Z","caller":"traceutil/trace.go:171","msg":"trace[1882518923] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"103.0865ms","start":"2026-04-13T01:31:46.820198Z","end":"2026-04-13T01:31:46.923284Z","steps":["trace[1882518923] 'process raft request' (duration: 82.860765ms)","trace[1882518923] 'compare' (duration: 20.103393ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:31:56.801217Z","caller":"traceutil/trace.go:171","msg":"trace[980218359] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"128.957453ms","start":"2026-04-13T01:31:56.672229Z","end":"2026-04-13T01:31:56.801187Z","steps":["trace[980218359] 'process raft request' (duration: 128.745588ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:32:36.638848Z","caller":"traceutil/trace.go:171","msg":"trace[1029519124] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2268; }","duration":"154.601027ms","start":"2026-04-13T01:32:36.484218Z","end":"2026-04-13T01:32:36.638819Z","steps":["trace[1029519124] 'read index received' (duration: 154.28904ms)","trace[1029519124] 'applied index is now lower than readState.Index' (duration: 310.817µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:32:36.6389Z","caller":"traceutil/trace.go:171","msg":"trace[1161196821] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"168.07045ms","start":"2026-04-13T01:32:36.470803Z","end":"2026-04-13T01:32:36.638873Z","steps":["trace[1161196821] 'process raft request' (duration: 167.837265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:32:36.639067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.827842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:32:36.639109Z","caller":"traceutil/trace.go:171","msg":"trace[1051759712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2184; }","duration":"154.902323ms","start":"2026-04-13T01:32:36.484196Z","end":"2026-04-13T01:32:36.639098Z","steps":["trace[1051759712] 'agreement among raft nodes before linearized reading' (duration: 154.818601ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:32:37.112869Z","caller":"traceutil/trace.go:171","msg":"trace[96783787] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"155.970969ms","start":"2026-04-13T01:32:36.956862Z","end":"2026-04-13T01:32:37.112833Z","steps":["trace[96783787] 'process raft request' (duration: 155.696173ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:32:37.11713Z","caller":"traceutil/trace.go:171","msg":"trace[1161007522] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"150.188605ms","start":"2026-04-13T01:32:36.96692Z","end":"2026-04-13T01:32:37.117109Z","steps":["trace[1161007522] 'process raft request' (duration: 149.890258ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:33:09.681707Z","caller":"traceutil/trace.go:171","msg":"trace[2012858815] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"189.422332ms","start":"2026-04-13T01:33:09.492254Z","end":"2026-04-13T01:33:09.681677Z","steps":["trace[2012858815] 'process raft request' (duration: 189.291419ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:33:43.510472Z","caller":"traceutil/trace.go:171","msg":"trace[1290191716] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"142.040779ms","start":"2026-04-13T01:33:43.368406Z","end":"2026-04-13T01:33:43.510447Z","steps":["trace[1290191716] 'process raft request' (duration: 141.888575ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:33:43.63368Z","caller":"traceutil/trace.go:171","msg":"trace[1005675060] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"117.066353ms","start":"2026-04-13T01:33:43.516582Z","end":"2026-04-13T01:33:43.633648Z","steps":["trace[1005675060] 'process raft request' (duration: 116.899989ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:33:43.634202Z","caller":"traceutil/trace.go:171","msg":"trace[1252607824] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"118.612108ms","start":"2026-04-13T01:33:43.515576Z","end":"2026-04-13T01:33:43.634188Z","steps":["trace[1252607824] 'process raft request' (duration: 100.004309ms)","trace[1252607824] 'compare' (duration: 17.742479ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:33:44.122581Z","caller":"traceutil/trace.go:171","msg":"trace[64234270] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"175.094843ms","start":"2026-04-13T01:33:43.947453Z","end":"2026-04-13T01:33:44.122548Z","steps":["trace[64234270] 'process raft request' (duration: 156.657587ms)","trace[64234270] 'compare' (duration: 18.102828ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:33:44.217937Z","caller":"traceutil/trace.go:171","msg":"trace[1693165780] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"223.349916ms","start":"2026-04-13T01:33:43.994514Z","end":"2026-04-13T01:33:44.217864Z","steps":["trace[1693165780] 'process raft request' (duration: 223.147041ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:35:34.314571Z","caller":"traceutil/trace.go:171","msg":"trace[2013498343] transaction","detail":"{read_only:false; response_revision:3474; number_of_response:1; }","duration":"105.620974ms","start":"2026-04-13T01:35:34.208913Z","end":"2026-04-13T01:35:34.314534Z","steps":["trace[2013498343] 'process raft request' (duration: 98.636502ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:35:34.315175Z","caller":"traceutil/trace.go:171","msg":"trace[2035202782] transaction","detail":"{read_only:false; response_revision:3475; number_of_response:1; }","duration":"104.327145ms","start":"2026-04-13T01:35:34.210824Z","end":"2026-04-13T01:35:34.315151Z","steps":["trace[2035202782] 'process raft request' (duration: 104.12284ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:35:34.315606Z","caller":"traceutil/trace.go:171","msg":"trace[795346771] transaction","detail":"{read_only:false; response_revision:3476; number_of_response:1; }","duration":"101.919308ms","start":"2026-04-13T01:35:34.213646Z","end":"2026-04-13T01:35:34.315565Z","steps":["trace[795346771] 'process raft request' (duration: 101.459498ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:37:27.185973Z","caller":"traceutil/trace.go:171","msg":"trace[1005863550] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"174.09824ms","start":"2026-04-13T01:37:27.011851Z","end":"2026-04-13T01:37:27.185949Z","steps":["trace[1005863550] 'process raft request' (duration: 173.898076ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:37:27.217897Z","caller":"traceutil/trace.go:171","msg":"trace[1530147559] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"182.22181ms","start":"2026-04-13T01:37:27.035644Z","end":"2026-04-13T01:37:27.217866Z","steps":["trace[1530147559] 'process raft request' (duration: 181.992204ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:37:33.804329Z","caller":"traceutil/trace.go:171","msg":"trace[625117428] linearizableReadLoop","detail":"{readStateIndex:5116; appliedIndex:5115; }","duration":"108.835308ms","start":"2026-04-13T01:37:33.695477Z","end":"2026-04-13T01:37:33.804312Z","steps":["trace[625117428] 'read index received' (duration: 108.665604ms)","trace[625117428] 'applied index is now lower than readState.Index' (duration: 169.024µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:37:33.804626Z","caller":"traceutil/trace.go:171","msg":"trace[313350813] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"110.141848ms","start":"2026-04-13T01:37:33.694477Z","end":"2026-04-13T01:37:33.804619Z","steps":["trace[313350813] 'process raft request' (duration: 109.724259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:37:33.805036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.526134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6hv56\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-13T01:37:33.805332Z","caller":"traceutil/trace.go:171","msg":"trace[1779338329] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6hv56; range_end:; response_count:1; response_revision:4926; }","duration":"109.872082ms","start":"2026-04-13T01:37:33.695434Z","end":"2026-04-13T01:37:33.805306Z","steps":["trace[1779338329] 'agreement among raft nodes before linearized reading' (duration: 109.3624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:37:51.676436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.047957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T01:37:51.676588Z","caller":"traceutil/trace.go:171","msg":"trace[1273461455] linearizableReadLoop","detail":"{readStateIndex:5471; appliedIndex:5470; }","duration":"166.36996ms","start":"2026-04-13T01:37:51.510193Z","end":"2026-04-13T01:37:51.676563Z","steps":["trace[1273461455] 'read index received' (duration: 10.989947ms)","trace[1273461455] 'applied index is now lower than readState.Index' (duration: 155.377703ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:37:51.676627Z","caller":"traceutil/trace.go:171","msg":"trace[1383447476] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"251.188458ms","start":"2026-04-13T01:37:51.425403Z","end":"2026-04-13T01:37:51.676591Z","steps":["trace[1383447476] 'process raft request' (duration: 95.866706ms)","trace[1383447476] 'compare' (duration: 154.80435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:37:51.676795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.593005ms","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-04-13T01:37:51.676881Z","caller":"traceutil/trace.go:171","msg":"trace[1180851634] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5273; }","duration":"166.723588ms","start":"2026-04-13T01:37:51.510141Z","end":"2026-04-13T01:37:51.676864Z","steps":["trace[1180851634] 'agreement among raft nodes before linearized reading' (duration: 166.492533ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:37:51.851761Z","caller":"traceutil/trace.go:171","msg":"trace[926912725] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"169.448982ms","start":"2026-04-13T01:37:51.682244Z","end":"2026-04-13T01:37:51.851693Z","steps":["trace[926912725] 'process raft request' (duration: 154.36367ms)","trace[926912725] 'compare' (duration: 14.812846ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:37:51.851855Z","caller":"traceutil/trace.go:171","msg":"trace[520347054] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"169.469592ms","start":"2026-04-13T01:37:51.682357Z","end":"2026-04-13T01:37:51.851827Z","steps":["trace[520347054] 'process raft request' (duration: 169.189895ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:37:51.852046Z","caller":"traceutil/trace.go:171","msg":"trace[1498159968] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"168.759626ms","start":"2026-04-13T01:37:51.68326Z","end":"2026-04-13T01:37:51.85202Z","steps":["trace[1498159968] 'process raft request' (duration: 168.54332ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:37:51.852394Z","caller":"traceutil/trace.go:171","msg":"trace[1746968049] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"168.731845ms","start":"2026-04-13T01:37:51.683643Z","end":"2026-04-13T01:37:51.852375Z","steps":["trace[1746968049] 'process raft request' (duration: 168.302615ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:37:51.852452Z","caller":"traceutil/trace.go:171","msg":"trace[985452300] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"166.021362ms","start":"2026-04-13T01:37:51.68641Z","end":"2026-04-13T01:37:51.852431Z","steps":["trace[985452300] 'process raft request' (duration: 165.804627ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:37:51.852568Z","caller":"traceutil/trace.go:171","msg":"trace[649013931] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"163.33641ms","start":"2026-04-13T01:37:51.689208Z","end":"2026-04-13T01:37:51.852545Z","steps":["trace[649013931] 'process raft request' (duration: 163.154886ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:37:52.078131Z","caller":"traceutil/trace.go:171","msg":"trace[1632095439] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"127.302629ms","start":"2026-04-13T01:37:51.950799Z","end":"2026-04-13T01:37:52.078102Z","steps":["trace[1632095439] 'process raft request' (duration: 94.183537ms)","trace[1632095439] 'compare' (duration: 32.756204ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:38:03.756869Z","caller":"traceutil/trace.go:171","msg":"trace[287688613] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"345.888717ms","start":"2026-04-13T01:38:03.410951Z","end":"2026-04-13T01:38:03.75684Z","steps":["trace[287688613] 'process raft request' (duration: 345.658062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:38:03.757136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:38:03.410921Z","time spent":"346.041171ms","remote":"127.0.0.1:49394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T01:38:03.793143Z","caller":"traceutil/trace.go:171","msg":"trace[1711482132] linearizableReadLoop","detail":"{readStateIndex:5679; appliedIndex:5677; }","duration":"228.416147ms","start":"2026-04-13T01:38:03.564698Z","end":"2026-04-13T01:38:03.793114Z","steps":["trace[1711482132] 'read index received' (duration: 191.921406ms)","trace[1711482132] 'applied index is now lower than readState.Index' (duration: 36.493901ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:38:03.793182Z","caller":"traceutil/trace.go:171","msg":"trace[1400347018] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"288.99638ms","start":"2026-04-13T01:38:03.504156Z","end":"2026-04-13T01:38:03.793152Z","steps":["trace[1400347018] 'process raft request' (duration: 288.740664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:38:03.79331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.600312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:38:03.793372Z","caller":"traceutil/trace.go:171","msg":"trace[1816635954] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5475; }","duration":"228.733195ms","start":"2026-04-13T01:38:03.564619Z","end":"2026-04-13T01:38:03.793352Z","steps":["trace[1816635954] 'agreement among raft nodes before linearized reading' (duration: 228.630672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:38:03.793447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.904316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-13T01:38:03.793527Z","caller":"traceutil/trace.go:171","msg":"trace[862694229] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5475; }","duration":"140.999909ms","start":"2026-04-13T01:38:03.652489Z","end":"2026-04-13T01:38:03.793489Z","steps":["trace[862694229] 'agreement among raft nodes before linearized reading' (duration: 140.788344ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:39:27.688632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2988} {"level":"info","ts":"2026-04-13T01:39:27.767889Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2988,"took":"77.43839ms","hash":357918639,"current-db-size-bytes":59166720,"current-db-size":"59 MB","current-db-size-in-use-bytes":34267136,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-13T01:39:27.76795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":357918639,"revision":2988,"compact-revision":-1} {"level":"info","ts":"2026-04-13T01:42:10.325293Z","caller":"traceutil/trace.go:171","msg":"trace[555548054] linearizableReadLoop","detail":"{readStateIndex:7523; appliedIndex:7522; }","duration":"148.963785ms","start":"2026-04-13T01:42:10.176301Z","end":"2026-04-13T01:42:10.325265Z","steps":["trace[555548054] 'read index received' (duration: 148.74329ms)","trace[555548054] 'applied index is now lower than readState.Index' (duration: 219.155µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:42:10.325355Z","caller":"traceutil/trace.go:171","msg":"trace[1226454756] transaction","detail":"{read_only:false; response_revision:7259; number_of_response:1; }","duration":"158.766445ms","start":"2026-04-13T01:42:10.166548Z","end":"2026-04-13T01:42:10.325314Z","steps":["trace[1226454756] 'process raft request' (duration: 158.55029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:42:10.325742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.390455ms","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-13T01:42:10.325847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.667898ms","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-13T01:42:10.325858Z","caller":"traceutil/trace.go:171","msg":"trace[1124263453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7259; }","duration":"149.550099ms","start":"2026-04-13T01:42:10.176283Z","end":"2026-04-13T01:42:10.325834Z","steps":["trace[1124263453] 'agreement among raft nodes before linearized reading' (duration: 149.109438ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:42:10.325909Z","caller":"traceutil/trace.go:171","msg":"trace[32891617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7259; }","duration":"136.77563ms","start":"2026-04-13T01:42:10.189116Z","end":"2026-04-13T01:42:10.325892Z","steps":["trace[32891617] 'agreement among raft nodes before linearized reading' (duration: 136.33497ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:42:10.49743Z","caller":"traceutil/trace.go:171","msg":"trace[573279550] linearizableReadLoop","detail":"{readStateIndex:7525; appliedIndex:7523; }","duration":"110.155837ms","start":"2026-04-13T01:42:10.387249Z","end":"2026-04-13T01:42:10.497405Z","steps":["trace[573279550] 'read index received' (duration: 69.057605ms)","trace[573279550] 'applied index is now lower than readState.Index' (duration: 41.097312ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:42:10.497463Z","caller":"traceutil/trace.go:171","msg":"trace[1587125041] transaction","detail":"{read_only:false; response_revision:7260; number_of_response:1; }","duration":"167.12319ms","start":"2026-04-13T01:42:10.330317Z","end":"2026-04-13T01:42:10.49744Z","steps":["trace[1587125041] 'process raft request' (duration: 125.921276ms)","trace[1587125041] 'compare' (duration: 40.699312ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:42:10.497467Z","caller":"traceutil/trace.go:171","msg":"trace[825426997] transaction","detail":"{read_only:false; response_revision:7261; number_of_response:1; }","duration":"166.896815ms","start":"2026-04-13T01:42:10.330539Z","end":"2026-04-13T01:42:10.497436Z","steps":["trace[825426997] 'process raft request' (duration: 166.777562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:42:10.497564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.304731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:42:10.497615Z","caller":"traceutil/trace.go:171","msg":"trace[2048749316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7261; }","duration":"110.423083ms","start":"2026-04-13T01:42:10.387177Z","end":"2026-04-13T01:42:10.4976Z","steps":["trace[2048749316] 'agreement among raft nodes before linearized reading' (duration: 110.331601ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:44:02.510541Z","caller":"traceutil/trace.go:171","msg":"trace[728827129] linearizableReadLoop","detail":"{readStateIndex:8452; appliedIndex:8451; }","duration":"135.469559ms","start":"2026-04-13T01:44:02.375042Z","end":"2026-04-13T01:44:02.510511Z","steps":["trace[728827129] 'read index received' (duration: 135.282314ms)","trace[728827129] 'applied index is now lower than readState.Index' (duration: 186.055µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:44:02.510624Z","caller":"traceutil/trace.go:171","msg":"trace[1678622025] transaction","detail":"{read_only:false; response_revision:8159; number_of_response:1; }","duration":"171.951327ms","start":"2026-04-13T01:44:02.338653Z","end":"2026-04-13T01:44:02.510605Z","steps":["trace[1678622025] 'process raft request' (duration: 171.65039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:44:02.51078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.719714ms","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-13T01:44:02.510826Z","caller":"traceutil/trace.go:171","msg":"trace[961562934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8159; }","duration":"135.817787ms","start":"2026-04-13T01:44:02.374997Z","end":"2026-04-13T01:44:02.510815Z","steps":["trace[961562934] 'agreement among raft nodes before linearized reading' (duration: 135.643973ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:44:02.686216Z","caller":"traceutil/trace.go:171","msg":"trace[362644719] transaction","detail":"{read_only:false; response_revision:8160; number_of_response:1; }","duration":"176.241187ms","start":"2026-04-13T01:44:02.509938Z","end":"2026-04-13T01:44:02.686179Z","steps":["trace[362644719] 'process raft request' (duration: 136.989033ms)","trace[362644719] 'compare' (duration: 39.013068ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:44:02.686539Z","caller":"traceutil/trace.go:171","msg":"trace[2055076454] transaction","detail":"{read_only:false; response_revision:8161; number_of_response:1; }","duration":"171.291251ms","start":"2026-04-13T01:44:02.515207Z","end":"2026-04-13T01:44:02.686498Z","steps":["trace[2055076454] 'process raft request' (duration: 170.911812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:44:13.706812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.638103ms","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-04-13T01:44:13.706959Z","caller":"traceutil/trace.go:171","msg":"trace[1553206425] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:8219; }","duration":"172.848927ms","start":"2026-04-13T01:44:13.53408Z","end":"2026-04-13T01:44:13.706929Z","steps":["trace[1553206425] 'count revisions from in-memory index tree' (duration: 172.506939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:44:13.706948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.743366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T01:44:13.707088Z","caller":"traceutil/trace.go:171","msg":"trace[753744067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8219; }","duration":"144.945661ms","start":"2026-04-13T01:44:13.56212Z","end":"2026-04-13T01:44:13.707066Z","steps":["trace[753744067] 'range keys from in-memory index tree' (duration: 144.534651ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:44:13.997263Z","caller":"traceutil/trace.go:171","msg":"trace[75900197] transaction","detail":"{read_only:false; response_revision:8220; number_of_response:1; }","duration":"285.542379ms","start":"2026-04-13T01:44:13.711684Z","end":"2026-04-13T01:44:13.997226Z","steps":["trace[75900197] 'process raft request' (duration: 285.277383ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:44:14.817168Z","caller":"traceutil/trace.go:171","msg":"trace[1260505333] linearizableReadLoop","detail":"{readStateIndex:8517; appliedIndex:8516; }","duration":"154.054445ms","start":"2026-04-13T01:44:14.663086Z","end":"2026-04-13T01:44:14.81714Z","steps":["trace[1260505333] 'read index received' (duration: 153.704647ms)","trace[1260505333] 'applied index is now lower than readState.Index' (duration: 348.558µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:44:14.817233Z","caller":"traceutil/trace.go:171","msg":"trace[461999393] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"271.614152ms","start":"2026-04-13T01:44:14.545591Z","end":"2026-04-13T01:44:14.817205Z","steps":["trace[461999393] 'process raft request' (duration: 271.210212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:44:14.817446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.325202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-13T01:44:14.817499Z","caller":"traceutil/trace.go:171","msg":"trace[874321676] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8222; }","duration":"154.457355ms","start":"2026-04-13T01:44:14.663028Z","end":"2026-04-13T01:44:14.817485Z","steps":["trace[874321676] 'agreement among raft nodes before linearized reading' (duration: 154.24494ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:44:14.895439Z","caller":"traceutil/trace.go:171","msg":"trace[337779643] transaction","detail":"{read_only:false; response_revision:8223; number_of_response:1; }","duration":"165.820013ms","start":"2026-04-13T01:44:14.729579Z","end":"2026-04-13T01:44:14.895399Z","steps":["trace[337779643] 'process raft request' (duration: 129.743584ms)","trace[337779643] 'compare' (duration: 35.852473ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:44:14.895593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.741083ms","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-13T01:44:14.89568Z","caller":"traceutil/trace.go:171","msg":"trace[1959004503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8223; }","duration":"149.887418ms","start":"2026-04-13T01:44:14.74577Z","end":"2026-04-13T01:44:14.895657Z","steps":["trace[1959004503] 'agreement among raft nodes before linearized reading' (duration: 149.621332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:44:16.018933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.99044ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-13T01:44:16.357888Z","caller":"traceutil/trace.go:171","msg":"trace[723891911] linearizableReadLoop","detail":"{readStateIndex:8526; appliedIndex:8525; }","duration":"162.643588ms","start":"2026-04-13T01:44:16.195208Z","end":"2026-04-13T01:44:16.357852Z","steps":["trace[723891911] 'read index received' (duration: 97.621378ms)","trace[723891911] 'applied index is now lower than readState.Index' (duration: 65.02021ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:44:16.357988Z","caller":"traceutil/trace.go:171","msg":"trace[227054046] transaction","detail":"{read_only:false; response_revision:8230; number_of_response:1; }","duration":"231.275693ms","start":"2026-04-13T01:44:16.126668Z","end":"2026-04-13T01:44:16.357944Z","steps":["trace[227054046] 'process raft request' (duration: 166.086519ms)","trace[227054046] 'compare' (duration: 64.923768ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:44:16.358147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.921644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6hv56\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-13T01:44:16.358201Z","caller":"traceutil/trace.go:171","msg":"trace[660987311] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6hv56; range_end:; response_count:1; response_revision:8230; }","duration":"163.024027ms","start":"2026-04-13T01:44:16.195162Z","end":"2026-04-13T01:44:16.358186Z","steps":["trace[660987311] 'agreement among raft nodes before linearized reading' (duration: 162.816302ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:44:27.694135Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6134} {"level":"info","ts":"2026-04-13T01:44:27.795429Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6134,"took":"99.284657ms","hash":3721042508,"current-db-size-bytes":59166720,"current-db-size":"59 MB","current-db-size-in-use-bytes":23060480,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-13T01:44:27.795507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3721042508,"revision":6134,"compact-revision":2988} {"level":"info","ts":"2026-04-13T01:44:53.50785Z","caller":"traceutil/trace.go:171","msg":"trace[228707798] linearizableReadLoop","detail":"{readStateIndex:8826; appliedIndex:8825; }","duration":"163.630721ms","start":"2026-04-13T01:44:53.344191Z","end":"2026-04-13T01:44:53.507822Z","steps":["trace[228707798] 'read index received' (duration: 83.462424ms)","trace[228707798] 'applied index is now lower than readState.Index' (duration: 80.166767ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:44:53.508112Z","caller":"traceutil/trace.go:171","msg":"trace[2139816296] transaction","detail":"{read_only:false; response_revision:8516; number_of_response:1; }","duration":"354.82225ms","start":"2026-04-13T01:44:53.153242Z","end":"2026-04-13T01:44:53.508065Z","steps":["trace[2139816296] 'process raft request' (duration: 274.48252ms)","trace[2139816296] 'compare' (duration: 79.954531ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:44:53.508204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.744681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T01:44:53.508242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.715738ms","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-04-13T01:44:53.508322Z","caller":"traceutil/trace.go:171","msg":"trace[1565612907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8516; }","duration":"156.849081ms","start":"2026-04-13T01:44:53.351455Z","end":"2026-04-13T01:44:53.508304Z","steps":["trace[1565612907] 'agreement among raft nodes before linearized reading' (duration: 156.624366ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:44:53.508325Z","caller":"traceutil/trace.go:171","msg":"trace[364524689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8516; }","duration":"120.912515ms","start":"2026-04-13T01:44:53.387385Z","end":"2026-04-13T01:44:53.508297Z","steps":["trace[364524689] 'agreement among raft nodes before linearized reading' (duration: 120.71662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:44:53.50828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.039731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-13T01:44:53.508389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:44:53.15321Z","time spent":"355.068866ms","remote":"127.0.0.1:49394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T01:44:53.508447Z","caller":"traceutil/trace.go:171","msg":"trace[582244419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8516; }","duration":"164.312407ms","start":"2026-04-13T01:44:53.344112Z","end":"2026-04-13T01:44:53.508424Z","steps":["trace[582244419] 'agreement among raft nodes before linearized reading' (duration: 163.933668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:45:05.283623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.627758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-ghxxx\" ","response":"range_response_count:1 size:4735"} {"level":"info","ts":"2026-04-13T01:45:05.283648Z","caller":"traceutil/trace.go:171","msg":"trace[961251631] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"269.9321ms","start":"2026-04-13T01:45:05.013686Z","end":"2026-04-13T01:45:05.283618Z","steps":["trace[961251631] 'process raft request' (duration: 231.640152ms)","trace[961251631] 'compare' (duration: 38.149805ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:45:05.28369Z","caller":"traceutil/trace.go:171","msg":"trace[1503521789] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-ghxxx; range_end:; response_count:1; response_revision:8578; }","duration":"282.72928ms","start":"2026-04-13T01:45:05.00094Z","end":"2026-04-13T01:45:05.283669Z","steps":["trace[1503521789] 'range keys from in-memory index tree' (duration: 282.482114ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:45:05.401416Z","caller":"traceutil/trace.go:171","msg":"trace[1950190816] linearizableReadLoop","detail":"{readStateIndex:8892; appliedIndex:8890; }","duration":"267.843181ms","start":"2026-04-13T01:45:05.133552Z","end":"2026-04-13T01:45:05.401395Z","steps":["trace[1950190816] 'read index received' (duration: 111.753771ms)","trace[1950190816] 'applied index is now lower than readState.Index' (duration: 156.08865ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:45:05.40149Z","caller":"traceutil/trace.go:171","msg":"trace[237041294] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"323.851715ms","start":"2026-04-13T01:45:05.077602Z","end":"2026-04-13T01:45:05.401454Z","steps":["trace[237041294] 'process raft request' (duration: 313.524002ms)","trace[237041294] 'compare' (duration: 10.176869ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:45:05.401687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:45:05.077571Z","time spent":"324.018109ms","remote":"127.0.0.1:49394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T01:45:05.401826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.23471ms","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-13T01:45:05.40195Z","caller":"traceutil/trace.go:171","msg":"trace[1267024098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8580; }","duration":"268.449265ms","start":"2026-04-13T01:45:05.133477Z","end":"2026-04-13T01:45:05.401926Z","steps":["trace[1267024098] 'agreement among raft nodes before linearized reading' (duration: 268.019315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:45:05.418209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.718206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T01:45:05.418293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.702349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:45:05.418341Z","caller":"traceutil/trace.go:171","msg":"trace[390508659] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8581; }","duration":"227.789181ms","start":"2026-04-13T01:45:05.190539Z","end":"2026-04-13T01:45:05.418328Z","steps":["trace[390508659] 'agreement among raft nodes before linearized reading' (duration: 227.713809ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:45:05.418351Z","caller":"traceutil/trace.go:171","msg":"trace[796504422] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"126.622019ms","start":"2026-04-13T01:45:05.291699Z","end":"2026-04-13T01:45:05.418321Z","steps":["trace[796504422] 'process raft request' (duration: 126.320732ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:45:05.418393Z","caller":"traceutil/trace.go:171","msg":"trace[1936918548] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:0; response_revision:8581; }","duration":"249.88743ms","start":"2026-04-13T01:45:05.168429Z","end":"2026-04-13T01:45:05.418316Z","steps":["trace[1936918548] 'agreement among raft nodes before linearized reading' (duration: 249.707006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:45:05.418254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.494107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:45:05.418592Z","caller":"traceutil/trace.go:171","msg":"trace[1833836470] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:8581; }","duration":"100.887965ms","start":"2026-04-13T01:45:05.317696Z","end":"2026-04-13T01:45:05.418584Z","steps":["trace[1833836470] 'agreement among raft nodes before linearized reading' (duration: 100.487076ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:45:06.157183Z","caller":"traceutil/trace.go:171","msg":"trace[920402325] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"150.309594ms","start":"2026-04-13T01:45:06.006843Z","end":"2026-04-13T01:45:06.157152Z","steps":["trace[920402325] 'process raft request' (duration: 150.12185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:45:06.401468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.523272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T01:45:06.401547Z","caller":"traceutil/trace.go:171","msg":"trace[1707717980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8596; }","duration":"116.649295ms","start":"2026-04-13T01:45:06.284881Z","end":"2026-04-13T01:45:06.40153Z","steps":["trace[1707717980] 'range keys from in-memory index tree' (duration: 116.346128ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:45:07.457724Z","caller":"traceutil/trace.go:171","msg":"trace[690305168] linearizableReadLoop","detail":"{readStateIndex:8918; appliedIndex:8917; }","duration":"154.472572ms","start":"2026-04-13T01:45:07.303225Z","end":"2026-04-13T01:45:07.457698Z","steps":["trace[690305168] 'read index received' (duration: 154.429221ms)","trace[690305168] 'applied index is now lower than readState.Index' (duration: 42.361µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:45:07.457802Z","caller":"traceutil/trace.go:171","msg":"trace[1200037122] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"168.367368ms","start":"2026-04-13T01:45:07.289413Z","end":"2026-04-13T01:45:07.45778Z","steps":["trace[1200037122] 'process raft request' (duration: 168.113462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:45:07.457887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.632306ms","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-04-13T01:45:07.457932Z","caller":"traceutil/trace.go:171","msg":"trace[347085370] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8603; }","duration":"154.733419ms","start":"2026-04-13T01:45:07.303187Z","end":"2026-04-13T01:45:07.45792Z","steps":["trace[347085370] 'agreement among raft nodes before linearized reading' (duration: 154.635916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:45:07.462852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.503083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:45:07.462937Z","caller":"traceutil/trace.go:171","msg":"trace[61592880] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:8604; }","duration":"131.603866ms","start":"2026-04-13T01:45:07.331313Z","end":"2026-04-13T01:45:07.462916Z","steps":["trace[61592880] 'agreement among raft nodes before linearized reading' (duration: 131.457212ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:45:36.597379Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002416.wal"} {"level":"info","ts":"2026-04-13T01:45:49.724708Z","caller":"traceutil/trace.go:171","msg":"trace[1975074359] transaction","detail":"{read_only:false; response_revision:9065; number_of_response:1; }","duration":"105.560795ms","start":"2026-04-13T01:45:49.619118Z","end":"2026-04-13T01:45:49.724679Z","steps":["trace[1975074359] 'process raft request' (duration: 105.305149ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:46:01.874974Z","caller":"traceutil/trace.go:171","msg":"trace[137049665] transaction","detail":"{read_only:false; response_revision:9170; number_of_response:1; }","duration":"114.199098ms","start":"2026-04-13T01:46:01.760751Z","end":"2026-04-13T01:46:01.87495Z","steps":["trace[137049665] 'process raft request' (duration: 112.063578ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:46:05.296926Z","caller":"traceutil/trace.go:171","msg":"trace[2072888186] transaction","detail":"{read_only:false; response_revision:9225; number_of_response:1; }","duration":"138.055757ms","start":"2026-04-13T01:46:05.158844Z","end":"2026-04-13T01:46:05.2969Z","steps":["trace[2072888186] 'process raft request' (duration: 137.881733ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:46:05.663547Z","caller":"traceutil/trace.go:171","msg":"trace[1816694579] transaction","detail":"{read_only:false; response_revision:9227; number_of_response:1; }","duration":"112.654502ms","start":"2026-04-13T01:46:05.550871Z","end":"2026-04-13T01:46:05.663526Z","steps":["trace[1816694579] 'process raft request' (duration: 112.598791ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:46:05.663871Z","caller":"traceutil/trace.go:171","msg":"trace[1320385245] transaction","detail":"{read_only:false; response_revision:9226; number_of_response:1; }","duration":"123.685281ms","start":"2026-04-13T01:46:05.540179Z","end":"2026-04-13T01:46:05.663865Z","steps":["trace[1320385245] 'process raft request' (duration: 120.220239ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:47:04.458231Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-13T01:47:04.463911Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-13T01:47:04.463989Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-13T01:49:27.700796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8302} {"level":"info","ts":"2026-04-13T01:49:27.782848Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8302,"took":"79.529091ms","hash":1076827448,"current-db-size-bytes":59166720,"current-db-size":"59 MB","current-db-size-in-use-bytes":24883200,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-13T01:49:27.782932Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1076827448,"revision":8302,"compact-revision":6134} {"level":"info","ts":"2026-04-13T01:50:35.92312Z","caller":"traceutil/trace.go:171","msg":"trace[1737793794] transaction","detail":"{read_only:false; response_revision:11059; number_of_response:1; }","duration":"103.755461ms","start":"2026-04-13T01:50:35.819323Z","end":"2026-04-13T01:50:35.923079Z","steps":["trace[1737793794] 'process raft request' (duration: 103.506345ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:54:27.709255Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10696} {"level":"info","ts":"2026-04-13T01:54:27.790332Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10696,"took":"78.618285ms","hash":2528731525,"current-db-size-bytes":59166720,"current-db-size":"59 MB","current-db-size-in-use-bytes":20885504,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-13T01:54:27.790411Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2528731525,"revision":10696,"compact-revision":8302}