{"level":"warn","ts":"2026-02-27T21:02:25.017394Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T21:02:25.017475Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227: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-02-27T21:02:25.017541Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T21:02:25.017555Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-27T21:02:25.017573Z","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-02-27T21:02:25.018193Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-02-27T21:02:25.018277Z","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://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227: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-02-27T21:02:25.022278Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.771894ms"} {"level":"info","ts":"2026-02-27T21:02:25.028348Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-02-27T21:02:25.028429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T21:02:25.028472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-02-27T21:02:25.028483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T21:02:25.028498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-02-27T21:02:25.028542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-02-27T21:02:25.035449Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T21:02:25.037237Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T21:02:25.039187Z","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-02-27T21:02:25.042945Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T21:02:25.043213Z","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-02-27T21:02:25.043303Z","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-02-27T21:02:25.043338Z","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-02-27T21:02:25.043267Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T21:02:25.045094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-02-27T21:02:25.045773Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-27T21:02:25.048304Z","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-02-27T21:02:25.048454Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-27T21:02:25.04859Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-27T21:02:25.04867Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T21:02:25.048716Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T21:02:25.229442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T21:02:25.229519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T21:02:25.22954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-02-27T21:02:25.229559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-02-27T21:02:25.229571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-27T21:02:25.229586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-02-27T21:02:25.229599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-27T21:02:25.230656Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T21:02:25.230729Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:02:25.230937Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T21:02:25.231058Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T21:02:25.23119Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T21:02:25.231242Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T21:02:25.234159Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:02:25.234441Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:02:25.234636Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:02:25.234667Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-02-27T21:02:25.236621Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T21:02:41.958667Z","caller":"traceutil/trace.go:171","msg":"trace[1245825446] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"120.191661ms","start":"2026-02-27T21:02:41.838439Z","end":"2026-02-27T21:02:41.958631Z","steps":["trace[1245825446] 'read index received' (duration: 119.921523ms)","trace[1245825446] 'applied index is now lower than readState.Index' (duration: 268.498µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:02:41.95873Z","caller":"traceutil/trace.go:171","msg":"trace[1918925105] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"143.702373ms","start":"2026-02-27T21:02:41.814981Z","end":"2026-02-27T21:02:41.958683Z","steps":["trace[1918925105] 'process raft request' (duration: 143.455526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:02:41.9589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.401226ms","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-02-27T21:02:41.958992Z","caller":"traceutil/trace.go:171","msg":"trace[727756298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"120.598882ms","start":"2026-02-27T21:02:41.838376Z","end":"2026-02-27T21:02:41.958975Z","steps":["trace[727756298] 'agreement among raft nodes before linearized reading' (duration: 120.411727ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:02:42.111643Z","caller":"traceutil/trace.go:171","msg":"trace[480711383] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"147.112575ms","start":"2026-02-27T21:02:41.964479Z","end":"2026-02-27T21:02:42.111592Z","steps":["trace[480711383] 'process raft request' (duration: 128.766597ms)","trace[480711383] 'compare' (duration: 17.971948ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:02:47.584452Z","caller":"traceutil/trace.go:171","msg":"trace[1719927954] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"164.686153ms","start":"2026-02-27T21:02:47.419724Z","end":"2026-02-27T21:02:47.58441Z","steps":["trace[1719927954] 'process raft request' (duration: 164.452927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:01.489423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.96174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cl44l\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-02-27T21:03:01.489516Z","caller":"traceutil/trace.go:171","msg":"trace[1221478518] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cl44l; range_end:; response_count:1; response_revision:513; }","duration":"104.119515ms","start":"2026-02-27T21:03:01.385377Z","end":"2026-02-27T21:03:01.489496Z","steps":["trace[1221478518] 'range keys from in-memory index tree' (duration: 103.727084ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:01.627653Z","caller":"traceutil/trace.go:171","msg":"trace[1186649349] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"106.606058ms","start":"2026-02-27T21:03:01.521027Z","end":"2026-02-27T21:03:01.627633Z","steps":["trace[1186649349] 'read index received' (duration: 106.448324ms)","trace[1186649349] 'applied index is now lower than readState.Index' (duration: 156.834µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:01.627716Z","caller":"traceutil/trace.go:171","msg":"trace[1034499539] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"123.907237ms","start":"2026-02-27T21:03:01.503773Z","end":"2026-02-27T21:03:01.62768Z","steps":["trace[1034499539] 'process raft request' (duration: 123.732552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:01.627812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.777333ms","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-02-27T21:03:01.627881Z","caller":"traceutil/trace.go:171","msg":"trace[482924500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"106.912276ms","start":"2026-02-27T21:03:01.520958Z","end":"2026-02-27T21:03:01.62787Z","steps":["trace[482924500] 'agreement among raft nodes before linearized reading' (duration: 106.757402ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:02.401083Z","caller":"traceutil/trace.go:171","msg":"trace[1058284698] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"153.986315ms","start":"2026-02-27T21:03:02.247068Z","end":"2026-02-27T21:03:02.401054Z","steps":["trace[1058284698] 'read index received' (duration: 153.862522ms)","trace[1058284698] 'applied index is now lower than readState.Index' (duration: 122.893µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:03:02.401242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.15021ms","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-02-27T21:03:02.401306Z","caller":"traceutil/trace.go:171","msg":"trace[1894617228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"154.263963ms","start":"2026-02-27T21:03:02.247026Z","end":"2026-02-27T21:03:02.40129Z","steps":["trace[1894617228] 'agreement among raft nodes before linearized reading' (duration: 154.134459ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:02.401658Z","caller":"traceutil/trace.go:171","msg":"trace[1467539933] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"246.512506ms","start":"2026-02-27T21:03:02.155134Z","end":"2026-02-27T21:03:02.401647Z","steps":["trace[1467539933] 'process raft request' (duration: 245.723855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:03.735607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.529435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:03:03.73589Z","caller":"traceutil/trace.go:171","msg":"trace[1211753540] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:583; }","duration":"204.077665ms","start":"2026-02-27T21:03:03.531795Z","end":"2026-02-27T21:03:03.735873Z","steps":["trace[1211753540] 'read index received' (duration: 4.600346ms)","trace[1211753540] 'applied index is now lower than readState.Index' (duration: 199.475469ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:03.735861Z","caller":"traceutil/trace.go:171","msg":"trace[281112224] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"208.261752ms","start":"2026-02-27T21:03:03.527546Z","end":"2026-02-27T21:03:03.735807Z","steps":["trace[281112224] 'process raft request' (duration: 26.044248ms)","trace[281112224] 'compare' (duration: 181.270048ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:03:03.735929Z","caller":"traceutil/trace.go:171","msg":"trace[37497614] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"208.237591ms","start":"2026-02-27T21:03:03.527652Z","end":"2026-02-27T21:03:03.73589Z","steps":["trace[37497614] 'process raft request' (duration: 208.085397ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:03.735992Z","caller":"traceutil/trace.go:171","msg":"trace[1317315987] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"202.849522ms","start":"2026-02-27T21:03:03.533083Z","end":"2026-02-27T21:03:03.735932Z","steps":["trace[1317315987] 'process raft request' (duration: 202.706038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:03.736074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.267831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-02-27T21:03:03.736105Z","caller":"traceutil/trace.go:171","msg":"trace[371175278] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:571; }","duration":"204.355713ms","start":"2026-02-27T21:03:03.531739Z","end":"2026-02-27T21:03:03.736095Z","steps":["trace[371175278] 'agreement among raft nodes before linearized reading' (duration: 204.21797ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:03.738477Z","caller":"traceutil/trace.go:171","msg":"trace[1576913707] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"195.630673ms","start":"2026-02-27T21:03:03.542819Z","end":"2026-02-27T21:03:03.73845Z","steps":["trace[1576913707] 'process raft request' (duration: 195.011516ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:03.738738Z","caller":"traceutil/trace.go:171","msg":"trace[1352728440] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"189.446093ms","start":"2026-02-27T21:03:03.549241Z","end":"2026-02-27T21:03:03.738687Z","steps":["trace[1352728440] 'process raft request' (duration: 188.866147ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:30.900627Z","caller":"traceutil/trace.go:171","msg":"trace[785611239] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"334.03099ms","start":"2026-02-27T21:03:30.566566Z","end":"2026-02-27T21:03:30.900597Z","steps":["trace[785611239] 'process raft request' (duration: 332.493838ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:03:30.901015Z","caller":"traceutil/trace.go:171","msg":"trace[1608286920] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"334.134572ms","start":"2026-02-27T21:03:30.566839Z","end":"2026-02-27T21:03:30.900973Z","steps":["trace[1608286920] 'process raft request' (duration: 333.853944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:30.901059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:30.566541Z","time spent":"334.189244ms","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:03:30.901186Z","caller":"traceutil/trace.go:171","msg":"trace[179417683] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"331.786468ms","start":"2026-02-27T21:03:30.569382Z","end":"2026-02-27T21:03:30.901168Z","steps":["trace[179417683] 'process raft request' (duration: 331.679894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:30.901266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:30.566807Z","time spent":"334.326387ms","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:03:30.901295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:30.569372Z","time spent":"331.864561ms","remote":"127.0.0.1:57732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4503,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:03:30.901449Z","caller":"traceutil/trace.go:171","msg":"trace[1965790702] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"334.001769ms","start":"2026-02-27T21:03:30.567434Z","end":"2026-02-27T21:03:30.901436Z","steps":["trace[1965790702] 'process raft request' (duration: 333.344491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:03:30.901525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:03:30.567422Z","time spent":"334.059132ms","remote":"127.0.0.1:57354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:04:15.27334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.957939ms","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-02-27T21:04:15.273449Z","caller":"traceutil/trace.go:171","msg":"trace[1167535672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1545; }","duration":"148.076642ms","start":"2026-02-27T21:04:15.125335Z","end":"2026-02-27T21:04:15.273412Z","steps":["trace[1167535672] 'agreement among raft nodes before linearized reading' (duration: 147.855016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:15.559623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.421393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:04:15.559756Z","caller":"traceutil/trace.go:171","msg":"trace[1850320976] linearizableReadLoop","detail":"{readStateIndex:1610; appliedIndex:1609; }","duration":"143.67858ms","start":"2026-02-27T21:04:15.416058Z","end":"2026-02-27T21:04:15.559737Z","steps":["trace[1850320976] 'read index received' (duration: 38.531µs)","trace[1850320976] 'applied index is now lower than readState.Index' (duration: 143.638249ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:04:15.559826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.773733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:04:15.559884Z","caller":"traceutil/trace.go:171","msg":"trace[1492958371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1547; }","duration":"143.855545ms","start":"2026-02-27T21:04:15.416018Z","end":"2026-02-27T21:04:15.559874Z","steps":["trace[1492958371] 'agreement among raft nodes before linearized reading' (duration: 143.767443ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:04:15.560115Z","caller":"traceutil/trace.go:171","msg":"trace[1255565298] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"283.004751ms","start":"2026-02-27T21:04:15.277071Z","end":"2026-02-27T21:04:15.560076Z","steps":["trace[1255565298] 'process raft request' (duration: 31.992803ms)","trace[1255565298] 'compare' (duration: 250.145245ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:04:15.869149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.440879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:04:15.869265Z","caller":"traceutil/trace.go:171","msg":"trace[943720976] linearizableReadLoop","detail":"{readStateIndex:1611; appliedIndex:1610; }","duration":"201.536295ms","start":"2026-02-27T21:04:15.667712Z","end":"2026-02-27T21:04:15.869248Z","steps":["trace[943720976] 'read index received' (duration: 36.831µs)","trace[943720976] 'applied index is now lower than readState.Index' (duration: 201.485874ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:04:15.869272Z","caller":"traceutil/trace.go:171","msg":"trace[630209] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"241.379724ms","start":"2026-02-27T21:04:15.627868Z","end":"2026-02-27T21:04:15.869247Z","steps":["trace[630209] 'process raft request' (duration: 37.742281ms)","trace[630209] 'compare' (duration: 203.304493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:04:15.869508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.790112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0a0754bb-baa4-490f-a540-ece8aabd47cc\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-02-27T21:04:15.869548Z","caller":"traceutil/trace.go:171","msg":"trace[541031776] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0a0754bb-baa4-490f-a540-ece8aabd47cc; range_end:; response_count:1; response_revision:1548; }","duration":"201.851584ms","start":"2026-02-27T21:04:15.667684Z","end":"2026-02-27T21:04:15.869536Z","steps":["trace[541031776] 'agreement among raft nodes before linearized reading' (duration: 201.657519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:04:15.869816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.783045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0a0754bb-baa4-490f-a540-ece8aabd47cc\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-02-27T21:04:15.869894Z","caller":"traceutil/trace.go:171","msg":"trace[594379707] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0a0754bb-baa4-490f-a540-ece8aabd47cc; range_end:; response_count:1; response_revision:1548; }","duration":"118.914688ms","start":"2026-02-27T21:04:15.750961Z","end":"2026-02-27T21:04:15.869875Z","steps":["trace[594379707] 'agreement among raft nodes before linearized reading' (duration: 118.523608ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:05:05.571048Z","caller":"traceutil/trace.go:171","msg":"trace[455015980] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"134.9546ms","start":"2026-02-27T21:05:05.436062Z","end":"2026-02-27T21:05:05.571016Z","steps":["trace[455015980] 'process raft request' (duration: 134.770595ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:05:05.603911Z","caller":"traceutil/trace.go:171","msg":"trace[1298976985] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"143.040733ms","start":"2026-02-27T21:05:05.460838Z","end":"2026-02-27T21:05:05.603879Z","steps":["trace[1298976985] 'process raft request' (duration: 142.805447ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:05:54.462344Z","caller":"traceutil/trace.go:171","msg":"trace[1366266896] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"104.416347ms","start":"2026-02-27T21:05:54.357895Z","end":"2026-02-27T21:05:54.462312Z","steps":["trace[1366266896] 'process raft request' (duration: 49.443647ms)","trace[1366266896] 'compare' (duration: 54.841386ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:05:54.767286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.994878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:05:54.767359Z","caller":"traceutil/trace.go:171","msg":"trace[1873086612] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2263; }","duration":"188.123552ms","start":"2026-02-27T21:05:54.579219Z","end":"2026-02-27T21:05:54.767342Z","steps":["trace[1873086612] 'count revisions from in-memory index tree' (duration: 187.905736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:05:54.767539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.045367ms","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-02-27T21:05:54.767555Z","caller":"traceutil/trace.go:171","msg":"trace[1582252533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2263; }","duration":"138.091477ms","start":"2026-02-27T21:05:54.629459Z","end":"2026-02-27T21:05:54.76755Z","steps":["trace[1582252533] 'range keys from in-memory index tree' (duration: 137.892392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:06:29.087411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.201077ms","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-02-27T21:06:29.087525Z","caller":"traceutil/trace.go:171","msg":"trace[995525800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2451; }","duration":"148.349601ms","start":"2026-02-27T21:06:28.939141Z","end":"2026-02-27T21:06:29.08749Z","steps":["trace[995525800] 'range keys from in-memory index tree' (duration: 148.018312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:06:29.087527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.88449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-02-27T21:06:29.087597Z","caller":"traceutil/trace.go:171","msg":"trace[819350780] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2451; }","duration":"134.033445ms","start":"2026-02-27T21:06:28.953547Z","end":"2026-02-27T21:06:29.087581Z","steps":["trace[819350780] 'range keys from in-memory index tree' (duration: 133.766048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:06:29.087744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.919509ms","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-02-27T21:06:29.087874Z","caller":"traceutil/trace.go:171","msg":"trace[439650963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2451; }","duration":"148.074653ms","start":"2026-02-27T21:06:28.939753Z","end":"2026-02-27T21:06:29.087828Z","steps":["trace[439650963] 'range keys from in-memory index tree' (duration: 147.709663ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:06:29.210348Z","caller":"traceutil/trace.go:171","msg":"trace[1215737472] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"118.731902ms","start":"2026-02-27T21:06:29.091592Z","end":"2026-02-27T21:06:29.210324Z","steps":["trace[1215737472] 'process raft request' (duration: 118.535107ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:06:29.38913Z","caller":"traceutil/trace.go:171","msg":"trace[649992540] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"297.333098ms","start":"2026-02-27T21:06:29.091762Z","end":"2026-02-27T21:06:29.389095Z","steps":["trace[649992540] 'process raft request' (duration: 241.357931ms)","trace[649992540] 'compare' (duration: 55.674209ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:06:29.389104Z","caller":"traceutil/trace.go:171","msg":"trace[1418938013] linearizableReadLoop","detail":"{readStateIndex:2554; appliedIndex:2551; }","duration":"295.688364ms","start":"2026-02-27T21:06:29.093381Z","end":"2026-02-27T21:06:29.389069Z","steps":["trace[1418938013] 'read index received' (duration: 116.760038ms)","trace[1418938013] 'applied index is now lower than readState.Index' (duration: 178.927086ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:06:29.38946Z","caller":"traceutil/trace.go:171","msg":"trace[1486132615] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"297.547564ms","start":"2026-02-27T21:06:29.091858Z","end":"2026-02-27T21:06:29.389405Z","steps":["trace[1486132615] 'process raft request' (duration: 297.151903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:06:29.389552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.148436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T21:06:29.389635Z","caller":"traceutil/trace.go:171","msg":"trace[423320545] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2454; }","duration":"296.278339ms","start":"2026-02-27T21:06:29.093335Z","end":"2026-02-27T21:06:29.389614Z","steps":["trace[423320545] 'agreement among raft nodes before linearized reading' (duration: 296.034633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:06:29.389678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.424362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:06:29.389768Z","caller":"traceutil/trace.go:171","msg":"trace[1797538947] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2454; }","duration":"182.579246ms","start":"2026-02-27T21:06:29.207177Z","end":"2026-02-27T21:06:29.389756Z","steps":["trace[1797538947] 'agreement among raft nodes before linearized reading' (duration: 182.398611ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:09:04.587717Z","caller":"traceutil/trace.go:171","msg":"trace[1718812134] linearizableReadLoop","detail":"{readStateIndex:3535; appliedIndex:3534; }","duration":"121.694215ms","start":"2026-02-27T21:09:04.465998Z","end":"2026-02-27T21:09:04.587692Z","steps":["trace[1718812134] 'read index received' (duration: 121.656064ms)","trace[1718812134] 'applied index is now lower than readState.Index' (duration: 37.221µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:09:04.587954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.933872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z4v94\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-27T21:09:04.587993Z","caller":"traceutil/trace.go:171","msg":"trace[1201912985] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z4v94; range_end:; response_count:1; response_revision:3396; }","duration":"122.009604ms","start":"2026-02-27T21:09:04.465972Z","end":"2026-02-27T21:09:04.587982Z","steps":["trace[1201912985] 'agreement among raft nodes before linearized reading' (duration: 121.819629ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:09:04.588171Z","caller":"traceutil/trace.go:171","msg":"trace[1672593268] transaction","detail":"{read_only:false; response_revision:3396; number_of_response:1; }","duration":"157.121225ms","start":"2026-02-27T21:09:04.431023Z","end":"2026-02-27T21:09:04.588144Z","steps":["trace[1672593268] 'process raft request' (duration: 156.54114ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:10:17.818525Z","caller":"traceutil/trace.go:171","msg":"trace[1152172601] transaction","detail":"{read_only:false; response_revision:4328; number_of_response:1; }","duration":"120.994565ms","start":"2026-02-27T21:10:17.69749Z","end":"2026-02-27T21:10:17.818484Z","steps":["trace[1152172601] 'process raft request' (duration: 34.777934ms)","trace[1152172601] 'compare' (duration: 86.032156ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:10:17.975668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.258197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-02-27T21:10:17.975758Z","caller":"traceutil/trace.go:171","msg":"trace[576510238] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4334; }","duration":"101.387961ms","start":"2026-02-27T21:10:17.87435Z","end":"2026-02-27T21:10:17.975738Z","steps":["trace[576510238] 'agreement among raft nodes before linearized reading' (duration: 101.140494ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:10:22.629198Z","caller":"traceutil/trace.go:171","msg":"trace[1798904377] linearizableReadLoop","detail":"{readStateIndex:4690; appliedIndex:4689; }","duration":"112.831158ms","start":"2026-02-27T21:10:22.516346Z","end":"2026-02-27T21:10:22.629177Z","steps":["trace[1798904377] 'read index received' (duration: 112.647253ms)","trace[1798904377] 'applied index is now lower than readState.Index' (duration: 183.065µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:10:22.629256Z","caller":"traceutil/trace.go:171","msg":"trace[193497147] transaction","detail":"{read_only:false; response_revision:4509; number_of_response:1; }","duration":"122.818505ms","start":"2026-02-27T21:10:22.506412Z","end":"2026-02-27T21:10:22.629231Z","steps":["trace[193497147] 'process raft request' (duration: 122.632819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:22.629699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.312573ms","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-02-27T21:10:22.629813Z","caller":"traceutil/trace.go:171","msg":"trace[116281513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4509; }","duration":"113.483957ms","start":"2026-02-27T21:10:22.516305Z","end":"2026-02-27T21:10:22.629789Z","steps":["trace[116281513] 'agreement among raft nodes before linearized reading' (duration: 113.000283ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:10:22.814634Z","caller":"traceutil/trace.go:171","msg":"trace[258793335] transaction","detail":"{read_only:false; response_revision:4511; number_of_response:1; }","duration":"179.065185ms","start":"2026-02-27T21:10:22.635544Z","end":"2026-02-27T21:10:22.814609Z","steps":["trace[258793335] 'process raft request' (duration: 178.963802ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:10:22.814618Z","caller":"traceutil/trace.go:171","msg":"trace[58836204] linearizableReadLoop","detail":"{readStateIndex:4692; appliedIndex:4690; }","duration":"165.511699ms","start":"2026-02-27T21:10:22.649072Z","end":"2026-02-27T21:10:22.814584Z","steps":["trace[58836204] 'read index received' (duration: 99.907289ms)","trace[58836204] 'applied index is now lower than readState.Index' (duration: 65.60304ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:10:22.814796Z","caller":"traceutil/trace.go:171","msg":"trace[1327908115] transaction","detail":"{read_only:false; response_revision:4510; number_of_response:1; }","duration":"180.474735ms","start":"2026-02-27T21:10:22.63429Z","end":"2026-02-27T21:10:22.814764Z","steps":["trace[1327908115] 'process raft request' (duration: 114.69508ms)","trace[1327908115] 'compare' (duration: 65.316242ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:10:22.814865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.765146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33f5f346-bfd3-49ef-9632-d4a53b346c7d\" ","response":"range_response_count:1 size:3718"} {"level":"info","ts":"2026-02-27T21:10:22.815092Z","caller":"traceutil/trace.go:171","msg":"trace[1539483485] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-33f5f346-bfd3-49ef-9632-d4a53b346c7d; range_end:; response_count:1; response_revision:4511; }","duration":"166.060354ms","start":"2026-02-27T21:10:22.649007Z","end":"2026-02-27T21:10:22.815067Z","steps":["trace[1539483485] 'agreement among raft nodes before linearized reading' (duration: 165.778456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:40.192709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.153717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:10:40.192958Z","caller":"traceutil/trace.go:171","msg":"trace[594178247] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"150.988611ms","start":"2026-02-27T21:10:40.041921Z","end":"2026-02-27T21:10:40.19291Z","steps":["trace[594178247] 'process raft request' (duration: 22.480724ms)","trace[594178247] 'compare' (duration: 127.985173ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:10:40.348802Z","caller":"traceutil/trace.go:171","msg":"trace[29035993] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"151.406343ms","start":"2026-02-27T21:10:40.197358Z","end":"2026-02-27T21:10:40.348765Z","steps":["trace[29035993] 'process raft request' (duration: 105.540989ms)","trace[29035993] 'compare' (duration: 45.255917ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:10:40.483814Z","caller":"traceutil/trace.go:171","msg":"trace[291683510] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"285.417152ms","start":"2026-02-27T21:10:40.198352Z","end":"2026-02-27T21:10:40.483769Z","steps":["trace[291683510] 'process raft request' (duration: 262.820075ms)","trace[291683510] 'compare' (duration: 22.35329ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:10:40.483948Z","caller":"traceutil/trace.go:171","msg":"trace[93760709] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"285.3664ms","start":"2026-02-27T21:10:40.1985Z","end":"2026-02-27T21:10:40.483867Z","steps":["trace[93760709] 'process raft request' (duration: 285.181555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:10:40.484165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.2243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-82bfa854-1e1a-41c1-be5e-c23c2373150c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-02-27T21:10:40.484227Z","caller":"traceutil/trace.go:171","msg":"trace[1491884022] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-82bfa854-1e1a-41c1-be5e-c23c2373150c; range_end:; response_count:1; response_revision:4837; }","duration":"230.365284ms","start":"2026-02-27T21:10:40.253848Z","end":"2026-02-27T21:10:40.484213Z","steps":["trace[1491884022] 'agreement among raft nodes before linearized reading' (duration: 230.067836ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:10:40.484398Z","caller":"traceutil/trace.go:171","msg":"trace[1973196693] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"281.660597ms","start":"2026-02-27T21:10:40.202701Z","end":"2026-02-27T21:10:40.484362Z","steps":["trace[1973196693] 'process raft request' (duration: 281.04595ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:10:40.483824Z","caller":"traceutil/trace.go:171","msg":"trace[507246672] linearizableReadLoop","detail":"{readStateIndex:5025; appliedIndex:5021; }","duration":"229.910441ms","start":"2026-02-27T21:10:40.253888Z","end":"2026-02-27T21:10:40.483798Z","steps":["trace[507246672] 'read index received' (duration: 49.02532ms)","trace[507246672] 'applied index is now lower than readState.Index' (duration: 180.884011ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:11:29.328334Z","caller":"traceutil/trace.go:171","msg":"trace[234017350] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"104.268124ms","start":"2026-02-27T21:11:29.224041Z","end":"2026-02-27T21:11:29.328309Z","steps":["trace[234017350] 'process raft request' (duration: 104.11737ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:12:25.604736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2731} {"level":"info","ts":"2026-02-27T21:12:25.66121Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2731,"took":"54.828875ms","hash":1617015635,"current-db-size-bytes":41447424,"current-db-size":"41 MB","current-db-size-in-use-bytes":31170560,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-02-27T21:12:25.661278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1617015635,"revision":2731,"compact-revision":-1} {"level":"warn","ts":"2026-02-27T21:16:18.932624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.726027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T21:16:18.93271Z","caller":"traceutil/trace.go:171","msg":"trace[55098342] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:7398; }","duration":"228.900842ms","start":"2026-02-27T21:16:18.703791Z","end":"2026-02-27T21:16:18.932691Z","steps":["trace[55098342] 'range keys from in-memory index tree' (duration: 228.548592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:16:18.932701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.097643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-02-27T21:16:18.93282Z","caller":"traceutil/trace.go:171","msg":"trace[1719276929] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7398; }","duration":"305.261687ms","start":"2026-02-27T21:16:18.627532Z","end":"2026-02-27T21:16:18.932793Z","steps":["trace[1719276929] 'range keys from in-memory index tree' (duration: 304.907827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:16:18.932889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:16:18.627514Z","time spent":"305.359059ms","remote":"127.0.0.1:57368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-02-27T21:16:30.376829Z","caller":"traceutil/trace.go:171","msg":"trace[1180727330] transaction","detail":"{read_only:false; response_revision:7448; number_of_response:1; }","duration":"248.817946ms","start":"2026-02-27T21:16:30.127983Z","end":"2026-02-27T21:16:30.376801Z","steps":["trace[1180727330] 'process raft request' (duration: 248.607701ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:16:30.386325Z","caller":"traceutil/trace.go:171","msg":"trace[327142187] linearizableReadLoop","detail":"{readStateIndex:7724; appliedIndex:7722; }","duration":"231.998227ms","start":"2026-02-27T21:16:30.1543Z","end":"2026-02-27T21:16:30.386298Z","steps":["trace[327142187] 'read index received' (duration: 222.400311ms)","trace[327142187] 'applied index is now lower than readState.Index' (duration: 9.596776ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:16:30.386435Z","caller":"traceutil/trace.go:171","msg":"trace[1424691426] transaction","detail":"{read_only:false; response_revision:7449; number_of_response:1; }","duration":"239.162706ms","start":"2026-02-27T21:16:30.147238Z","end":"2026-02-27T21:16:30.386401Z","steps":["trace[1424691426] 'process raft request' (duration: 238.93965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:16:30.386547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.220154ms","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-02-27T21:16:30.386552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.037194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:16:30.386594Z","caller":"traceutil/trace.go:171","msg":"trace[1010835026] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:7449; }","duration":"202.125306ms","start":"2026-02-27T21:16:30.184456Z","end":"2026-02-27T21:16:30.386582Z","steps":["trace[1010835026] 'agreement among raft nodes before linearized reading' (duration: 202.036334ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:16:30.386592Z","caller":"traceutil/trace.go:171","msg":"trace[2111304579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7449; }","duration":"232.309916ms","start":"2026-02-27T21:16:30.154267Z","end":"2026-02-27T21:16:30.386577Z","steps":["trace[2111304579] 'agreement among raft nodes before linearized reading' (duration: 232.155642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:16:30.386641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.837269ms","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-02-27T21:16:30.386707Z","caller":"traceutil/trace.go:171","msg":"trace[643981704] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7449; }","duration":"228.929591ms","start":"2026-02-27T21:16:30.157759Z","end":"2026-02-27T21:16:30.386688Z","steps":["trace[643981704] 'agreement among raft nodes before linearized reading' (duration: 228.736516ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:16:57.569289Z","caller":"traceutil/trace.go:171","msg":"trace[1569088590] transaction","detail":"{read_only:false; response_revision:7632; number_of_response:1; }","duration":"282.624107ms","start":"2026-02-27T21:16:57.286639Z","end":"2026-02-27T21:16:57.569263Z","steps":["trace[1569088590] 'process raft request' (duration: 282.485503ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:16:57.570058Z","caller":"traceutil/trace.go:171","msg":"trace[2107240494] linearizableReadLoop","detail":"{readStateIndex:7915; appliedIndex:7915; }","duration":"266.033635ms","start":"2026-02-27T21:16:57.304008Z","end":"2026-02-27T21:16:57.570042Z","steps":["trace[2107240494] 'read index received' (duration: 266.028765ms)","trace[2107240494] 'applied index is now lower than readState.Index' (duration: 3.72µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:16:57.570242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.21388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-27T21:16:57.570322Z","caller":"traceutil/trace.go:171","msg":"trace[594390261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7632; }","duration":"266.350514ms","start":"2026-02-27T21:16:57.30395Z","end":"2026-02-27T21:16:57.570301Z","steps":["trace[594390261] 'agreement among raft nodes before linearized reading' (duration: 266.136968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:16:57.570385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.956692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z4v94\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-27T21:16:57.570458Z","caller":"traceutil/trace.go:171","msg":"trace[1438586460] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z4v94; range_end:; response_count:1; response_revision:7632; }","duration":"262.161988ms","start":"2026-02-27T21:16:57.308283Z","end":"2026-02-27T21:16:57.570445Z","steps":["trace[1438586460] 'agreement among raft nodes before linearized reading' (duration: 261.913201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:16:57.570372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.443843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:16:57.570588Z","caller":"traceutil/trace.go:171","msg":"trace[505578816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7632; }","duration":"152.70361ms","start":"2026-02-27T21:16:57.417864Z","end":"2026-02-27T21:16:57.570567Z","steps":["trace[505578816] 'agreement among raft nodes before linearized reading' (duration: 152.439013ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:17:09.52334Z","caller":"traceutil/trace.go:171","msg":"trace[1741068751] transaction","detail":"{read_only:false; response_revision:7734; number_of_response:1; }","duration":"157.527718ms","start":"2026-02-27T21:17:09.365781Z","end":"2026-02-27T21:17:09.523309Z","steps":["trace[1741068751] 'process raft request' (duration: 157.388764ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:17:10.090247Z","caller":"traceutil/trace.go:171","msg":"trace[293558573] transaction","detail":"{read_only:false; response_revision:7738; number_of_response:1; }","duration":"186.567784ms","start":"2026-02-27T21:17:09.903652Z","end":"2026-02-27T21:17:10.090219Z","steps":["trace[293558573] 'process raft request' (duration: 186.324138ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:17:10.300335Z","caller":"traceutil/trace.go:171","msg":"trace[1848324637] linearizableReadLoop","detail":"{readStateIndex:8028; appliedIndex:8027; }","duration":"206.335294ms","start":"2026-02-27T21:17:10.093979Z","end":"2026-02-27T21:17:10.300314Z","steps":["trace[1848324637] 'read index received' (duration: 112.680001ms)","trace[1848324637] 'applied index is now lower than readState.Index' (duration: 93.654443ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:17:10.300493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.494169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-02-27T21:17:10.300521Z","caller":"traceutil/trace.go:171","msg":"trace[789313544] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7739; }","duration":"206.55832ms","start":"2026-02-27T21:17:10.093955Z","end":"2026-02-27T21:17:10.300513Z","steps":["trace[789313544] 'agreement among raft nodes before linearized reading' (duration: 206.440627ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:17:10.300541Z","caller":"traceutil/trace.go:171","msg":"trace[1981705028] transaction","detail":"{read_only:false; response_revision:7739; number_of_response:1; }","duration":"259.79682ms","start":"2026-02-27T21:17:10.040715Z","end":"2026-02-27T21:17:10.300511Z","steps":["trace[1981705028] 'process raft request' (duration: 165.917062ms)","trace[1981705028] 'compare' (duration: 93.58843ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:17:25.611192Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5746} {"level":"info","ts":"2026-02-27T21:17:25.699062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5746,"took":"85.685932ms","hash":1241050929,"current-db-size-bytes":43433984,"current-db-size":"43 MB","current-db-size-in-use-bytes":23273472,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-27T21:17:25.699138Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1241050929,"revision":5746,"compact-revision":2731} {"level":"info","ts":"2026-02-27T21:19:07.103818Z","caller":"traceutil/trace.go:171","msg":"trace[2013598069] transaction","detail":"{read_only:false; response_revision:8745; number_of_response:1; }","duration":"102.237211ms","start":"2026-02-27T21:19:07.001563Z","end":"2026-02-27T21:19:07.1038Z","steps":["trace[2013598069] 'process raft request' (duration: 42.45708ms)","trace[2013598069] 'compare' (duration: 59.35196ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:21:01.509538Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9bd37ed2859641e3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-27T21:21:01.514699Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-27T21:21:01.514773Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-27T21:21:57.953599Z","caller":"traceutil/trace.go:171","msg":"trace[696777984] transaction","detail":"{read_only:false; response_revision:10156; number_of_response:1; }","duration":"254.962536ms","start":"2026-02-27T21:21:57.69859Z","end":"2026-02-27T21:21:57.953553Z","steps":["trace[696777984] 'process raft request' (duration: 171.973343ms)","trace[696777984] 'compare' (duration: 82.503809ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:22:25.618044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7918} {"level":"info","ts":"2026-02-27T21:22:25.682412Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7918,"took":"62.332977ms","hash":2972476091,"current-db-size-bytes":43433984,"current-db-size":"43 MB","current-db-size-in-use-bytes":27561984,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-27T21:22:25.682476Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2972476091,"revision":7918,"compact-revision":5746} {"level":"info","ts":"2026-02-27T21:27:25.626235Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10340} {"level":"info","ts":"2026-02-27T21:27:25.699194Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10340,"took":"70.808862ms","hash":4097356425,"current-db-size-bytes":43433984,"current-db-size":"43 MB","current-db-size-in-use-bytes":24137728,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-27T21:27:25.69926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4097356425,"revision":10340,"compact-revision":7918} {"level":"info","ts":"2026-02-27T21:28:04.184275Z","caller":"traceutil/trace.go:171","msg":"trace[758549488] transaction","detail":"{read_only:false; response_revision:12461; number_of_response:1; }","duration":"132.814174ms","start":"2026-02-27T21:28:04.051433Z","end":"2026-02-27T21:28:04.184247Z","steps":["trace[758549488] 'process raft request' (duration: 132.587197ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:28:18.293151Z","caller":"traceutil/trace.go:171","msg":"trace[1990586043] transaction","detail":"{read_only:false; response_revision:12540; number_of_response:1; }","duration":"212.243978ms","start":"2026-02-27T21:28:18.080876Z","end":"2026-02-27T21:28:18.29312Z","steps":["trace[1990586043] 'process raft request' (duration: 211.964721ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:28:18.50072Z","caller":"traceutil/trace.go:171","msg":"trace[1480924564] transaction","detail":"{read_only:false; response_revision:12541; number_of_response:1; }","duration":"201.607011ms","start":"2026-02-27T21:28:18.299084Z","end":"2026-02-27T21:28:18.500691Z","steps":["trace[1480924564] 'process raft request' (duration: 201.250411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:28:26.680049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.065992ms","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-02-27T21:28:26.680055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.45323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b36ba10-6178-4038-8a43-b076755ec359\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-02-27T21:28:26.680142Z","caller":"traceutil/trace.go:171","msg":"trace[1015554418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12688; }","duration":"147.219896ms","start":"2026-02-27T21:28:26.532905Z","end":"2026-02-27T21:28:26.680125Z","steps":["trace[1015554418] 'range keys from in-memory index tree' (duration: 146.875776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:28:26.680198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.771748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c25994b-562d-4c96-9769-d1de451c9445\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-02-27T21:28:26.680242Z","caller":"traceutil/trace.go:171","msg":"trace[353886252] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c25994b-562d-4c96-9769-d1de451c9445; range_end:; response_count:1; response_revision:12688; }","duration":"162.861301ms","start":"2026-02-27T21:28:26.517368Z","end":"2026-02-27T21:28:26.680229Z","steps":["trace[353886252] 'range keys from in-memory index tree' (duration: 162.632005ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:28:26.680565Z","caller":"traceutil/trace.go:171","msg":"trace[1473787683] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b36ba10-6178-4038-8a43-b076755ec359; range_end:; response_count:1; response_revision:12688; }","duration":"162.590753ms","start":"2026-02-27T21:28:26.517552Z","end":"2026-02-27T21:28:26.680143Z","steps":["trace[1473787683] 'range keys from in-memory index tree' (duration: 162.221764ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:28:26.947114Z","caller":"traceutil/trace.go:171","msg":"trace[349183860] transaction","detail":"{read_only:false; response_revision:12690; number_of_response:1; }","duration":"101.614063ms","start":"2026-02-27T21:28:26.84547Z","end":"2026-02-27T21:28:26.947084Z","steps":["trace[349183860] 'process raft request' (duration: 33.188015ms)","trace[349183860] 'compare' (duration: 68.188431ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:28:32.316325Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000033e2.wal"} {"level":"info","ts":"2026-02-27T21:32:25.63239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12227} {"level":"info","ts":"2026-02-27T21:32:25.706034Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12227,"took":"71.66633ms","hash":3472186533,"current-db-size-bytes":43433984,"current-db-size":"43 MB","current-db-size-in-use-bytes":28741632,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-02-27T21:32:25.706103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3472186533,"revision":12227,"compact-revision":10340} {"level":"warn","ts":"2026-02-27T21:33:47.903992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.981371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:33:47.904216Z","caller":"traceutil/trace.go:171","msg":"trace[1403266748] transaction","detail":"{read_only:false; response_revision:15315; number_of_response:1; }","duration":"241.360304ms","start":"2026-02-27T21:33:47.662836Z","end":"2026-02-27T21:33:47.904197Z","steps":["trace[1403266748] 'process raft request' (duration: 241.266251ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:33:47.90421Z","caller":"traceutil/trace.go:171","msg":"trace[1893055916] transaction","detail":"{read_only:false; response_revision:15314; number_of_response:1; }","duration":"394.621391ms","start":"2026-02-27T21:33:47.509546Z","end":"2026-02-27T21:33:47.904167Z","steps":["trace[1893055916] 'process raft request' (duration: 276.373822ms)","trace[1893055916] 'compare' (duration: 117.663743ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:33:47.904234Z","caller":"traceutil/trace.go:171","msg":"trace[1524774436] linearizableReadLoop","detail":"{readStateIndex:15863; appliedIndex:15862; }","duration":"262.536396ms","start":"2026-02-27T21:33:47.641666Z","end":"2026-02-27T21:33:47.904202Z","steps":["trace[1524774436] 'read index received' (duration: 144.40208ms)","trace[1524774436] 'applied index is now lower than readState.Index' (duration: 118.131396ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:33:47.904345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:33:47.509532Z","time spent":"394.734164ms","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:33:47.904505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.002117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jpvzz\" ","response":"range_response_count:1 size:20864"} {"level":"info","ts":"2026-02-27T21:33:47.904551Z","caller":"traceutil/trace.go:171","msg":"trace[1405572341] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-jpvzz; range_end:; response_count:1; response_revision:15315; }","duration":"178.082569ms","start":"2026-02-27T21:33:47.726455Z","end":"2026-02-27T21:33:47.904538Z","steps":["trace[1405572341] 'agreement among raft nodes before linearized reading' (duration: 177.898714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:33:47.904587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.444308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8knhl\" ","response":"range_response_count:1 size:22125"} {"level":"warn","ts":"2026-02-27T21:33:47.904715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.993278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-02-27T21:33:47.904778Z","caller":"traceutil/trace.go:171","msg":"trace[434662105] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8knhl; range_end:; response_count:1; response_revision:15315; }","duration":"188.598842ms","start":"2026-02-27T21:33:47.716087Z","end":"2026-02-27T21:33:47.904686Z","steps":["trace[434662105] 'agreement among raft nodes before linearized reading' (duration: 188.268383ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:33:47.904838Z","caller":"traceutil/trace.go:171","msg":"trace[631998886] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15315; }","duration":"263.200734ms","start":"2026-02-27T21:33:47.641614Z","end":"2026-02-27T21:33:47.904814Z","steps":["trace[631998886] 'agreement among raft nodes before linearized reading' (duration: 262.6954ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:33:48.120435Z","caller":"traceutil/trace.go:171","msg":"trace[949865502] linearizableReadLoop","detail":"{readStateIndex:15865; appliedIndex:15864; }","duration":"209.539597ms","start":"2026-02-27T21:33:47.910857Z","end":"2026-02-27T21:33:48.120397Z","steps":["trace[949865502] 'read index received' (duration: 138.692801ms)","trace[949865502] 'applied index is now lower than readState.Index' (duration: 70.844846ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:33:48.120692Z","caller":"traceutil/trace.go:171","msg":"trace[567164049] transaction","detail":"{read_only:false; response_revision:15316; number_of_response:1; }","duration":"210.762401ms","start":"2026-02-27T21:33:47.909914Z","end":"2026-02-27T21:33:48.120676Z","steps":["trace[567164049] 'process raft request' (duration: 139.661997ms)","trace[567164049] 'compare' (duration: 70.629591ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:33:48.122557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.670267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:89 size:875315"} {"level":"info","ts":"2026-02-27T21:33:48.122633Z","caller":"traceutil/trace.go:171","msg":"trace[240304217] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:89; response_revision:15316; }","duration":"211.78567ms","start":"2026-02-27T21:33:47.91083Z","end":"2026-02-27T21:33:48.122615Z","steps":["trace[240304217] 'agreement among raft nodes before linearized reading' (duration: 209.852716ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:33:48.156666Z","caller":"traceutil/trace.go:171","msg":"trace[1996188726] transaction","detail":"{read_only:false; response_revision:15317; number_of_response:1; }","duration":"235.238463ms","start":"2026-02-27T21:33:47.921395Z","end":"2026-02-27T21:33:48.156633Z","steps":["trace[1996188726] 'process raft request' (duration: 235.077589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:33:48.168771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.084049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:33:48.168775Z","caller":"traceutil/trace.go:171","msg":"trace[1281700706] transaction","detail":"{read_only:false; response_revision:15318; number_of_response:1; }","duration":"111.362316ms","start":"2026-02-27T21:33:48.05739Z","end":"2026-02-27T21:33:48.168752Z","steps":["trace[1281700706] 'process raft request' (duration: 111.198092ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:33:48.168836Z","caller":"traceutil/trace.go:171","msg":"trace[461296460] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:15318; }","duration":"111.189562ms","start":"2026-02-27T21:33:48.05763Z","end":"2026-02-27T21:33:48.16882Z","steps":["trace[461296460] 'agreement among raft nodes before linearized reading' (duration: 111.062958ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:37:25.638964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14715} {"level":"info","ts":"2026-02-27T21:37:25.737847Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14715,"took":"95.293035ms","hash":2215370472,"current-db-size-bytes":43433984,"current-db-size":"43 MB","current-db-size-in-use-bytes":23592960,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-27T21:37:25.737916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2215370472,"revision":14715,"compact-revision":12227}