{"level":"warn","ts":"2026-04-11T17:47:54.315527Z","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-11T17:47:54.315681Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.138:2380","--initial-cluster=instance=https://199.19.213.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.138: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-11T17:47:54.315801Z","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-11T17:47:54.315824Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.138:2380"]} {"level":"info","ts":"2026-04-11T17:47:54.315861Z","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-11T17:47:54.317001Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"]} {"level":"info","ts":"2026-04-11T17:47:54.317139Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.138:2380"],"listen-peer-urls":["https://199.19.213.138:2380"],"advertise-client-urls":["https://199.19.213.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.138: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-11T17:47:54.322165Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.407459ms"} {"level":"info","ts":"2026-04-11T17:47:54.329373Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2062be27cfb43adb","cluster-id":"f353072e75ed4d57"} {"level":"info","ts":"2026-04-11T17:47:54.329527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T17:47:54.329572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became follower at term 0"} {"level":"info","ts":"2026-04-11T17:47:54.329592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2062be27cfb43adb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T17:47:54.329614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became follower at term 1"} {"level":"info","ts":"2026-04-11T17:47:54.329674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=(2333636635129035483)"} {"level":"warn","ts":"2026-04-11T17:47:54.336302Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T17:47:54.338315Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T17:47:54.340972Z","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-11T17:47:54.34476Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2062be27cfb43adb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T17:47:54.345213Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2062be27cfb43adb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T17:47:54.345396Z","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-11T17:47:54.3456Z","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-11T17:47:54.345628Z","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-11T17:47:54.349193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=(2333636635129035483)"} {"level":"info","ts":"2026-04-11T17:47:54.349406Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f353072e75ed4d57","local-member-id":"2062be27cfb43adb","added-peer-id":"2062be27cfb43adb","added-peer-peer-urls":["https://199.19.213.138:2380"]} {"level":"info","ts":"2026-04-11T17:47:54.352139Z","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-11T17:47:54.352322Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.138:2380"} {"level":"info","ts":"2026-04-11T17:47:54.352395Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.138:2380"} {"level":"info","ts":"2026-04-11T17:47:54.35251Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2062be27cfb43adb","initial-advertise-peer-urls":["https://199.19.213.138:2380"],"listen-peer-urls":["https://199.19.213.138:2380"],"advertise-client-urls":["https://199.19.213.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T17:47:54.352567Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T17:47:55.130408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T17:47:55.13058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T17:47:55.130625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb received MsgPreVoteResp from 2062be27cfb43adb at term 1"} {"level":"info","ts":"2026-04-11T17:47:55.130657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became candidate at term 2"} {"level":"info","ts":"2026-04-11T17:47:55.13067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb received MsgVoteResp from 2062be27cfb43adb at term 2"} {"level":"info","ts":"2026-04-11T17:47:55.130694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became leader at term 2"} {"level":"info","ts":"2026-04-11T17:47:55.13071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2062be27cfb43adb elected leader 2062be27cfb43adb at term 2"} {"level":"info","ts":"2026-04-11T17:47:55.132121Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2062be27cfb43adb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.138:2379]}","request-path":"/0/members/2062be27cfb43adb/attributes","cluster-id":"f353072e75ed4d57","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T17:47:55.132329Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:47:55.132458Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:47:55.132428Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:47:55.133642Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f353072e75ed4d57","local-member-id":"2062be27cfb43adb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:47:55.133841Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:47:55.133911Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T17:47:55.133914Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:47:55.133939Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T17:47:55.135013Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.138:2379"} {"level":"info","ts":"2026-04-11T17:47:55.136804Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-11T17:48:25.650742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.154978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:48:25.650915Z","caller":"traceutil/trace.go:171","msg":"trace[930466617] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"176.28582ms","start":"2026-04-11T17:48:25.474608Z","end":"2026-04-11T17:48:25.650894Z","steps":["trace[930466617] 'read index received' (duration: 74.761µs)","trace[930466617] 'applied index is now lower than readState.Index' (duration: 176.209769ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:25.650998Z","caller":"traceutil/trace.go:171","msg":"trace[1358383967] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"213.202676ms","start":"2026-04-11T17:48:25.437748Z","end":"2026-04-11T17:48:25.650951Z","steps":["trace[1358383967] 'process raft request' (duration: 31.186991ms)","trace[1358383967] 'compare' (duration: 180.925662ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:48:25.651132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.498975ms","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-11T17:48:25.651264Z","caller":"traceutil/trace.go:171","msg":"trace[304103171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"176.702279ms","start":"2026-04-11T17:48:25.474532Z","end":"2026-04-11T17:48:25.651234Z","steps":["trace[304103171] 'agreement among raft nodes before linearized reading' (duration: 176.461014ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:33.114382Z","caller":"traceutil/trace.go:171","msg":"trace[896100534] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"105.337049ms","start":"2026-04-11T17:48:33.009011Z","end":"2026-04-11T17:48:33.114348Z","steps":["trace[896100534] 'process raft request' (duration: 104.350649ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:33.363541Z","caller":"traceutil/trace.go:171","msg":"trace[1968361898] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"216.371479ms","start":"2026-04-11T17:48:33.14714Z","end":"2026-04-11T17:48:33.363512Z","steps":["trace[1968361898] 'process raft request' (duration: 153.786703ms)","trace[1968361898] 'compare' (duration: 62.336071ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:33.386656Z","caller":"traceutil/trace.go:171","msg":"trace[1163702468] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"203.744968ms","start":"2026-04-11T17:48:33.182888Z","end":"2026-04-11T17:48:33.386633Z","steps":["trace[1163702468] 'process raft request' (duration: 203.632505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:33.901781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.004254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:48:33.901991Z","caller":"traceutil/trace.go:171","msg":"trace[587197855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"319.287189ms","start":"2026-04-11T17:48:33.582678Z","end":"2026-04-11T17:48:33.901965Z","steps":["trace[587197855] 'range keys from in-memory index tree' (duration: 318.82811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:33.902071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:33.582617Z","time spent":"319.441422ms","remote":"127.0.0.1:35918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-11T17:48:33.902157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.957512ms","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-11T17:48:33.902299Z","caller":"traceutil/trace.go:171","msg":"trace[487149411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"211.189096ms","start":"2026-04-11T17:48:33.69108Z","end":"2026-04-11T17:48:33.902269Z","steps":["trace[487149411] 'range keys from in-memory index tree' (duration: 210.706806ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:44.636285Z","caller":"traceutil/trace.go:171","msg":"trace[1313263561] linearizableReadLoop","detail":"{readStateIndex:1460; appliedIndex:1459; }","duration":"110.267445ms","start":"2026-04-11T17:49:44.525977Z","end":"2026-04-11T17:49:44.636245Z","steps":["trace[1313263561] 'read index received' (duration: 110.0178ms)","trace[1313263561] 'applied index is now lower than readState.Index' (duration: 248.335µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:44.636365Z","caller":"traceutil/trace.go:171","msg":"trace[2140574713] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"110.97103ms","start":"2026-04-11T17:49:44.525342Z","end":"2026-04-11T17:49:44.636314Z","steps":["trace[2140574713] 'process raft request' (duration: 110.728105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:44.636569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.572951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:49:44.636618Z","caller":"traceutil/trace.go:171","msg":"trace[89877367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1418; }","duration":"110.698813ms","start":"2026-04-11T17:49:44.525906Z","end":"2026-04-11T17:49:44.636605Z","steps":["trace[89877367] 'agreement among raft nodes before linearized reading' (duration: 110.481939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:44.854775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.797047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:49:44.855108Z","caller":"traceutil/trace.go:171","msg":"trace[508685021] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1460; }","duration":"186.302314ms","start":"2026-04-11T17:49:44.668786Z","end":"2026-04-11T17:49:44.855088Z","steps":["trace[508685021] 'read index received' (duration: 46.861µs)","trace[508685021] 'applied index is now lower than readState.Index' (duration: 186.253793ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:44.855153Z","caller":"traceutil/trace.go:171","msg":"trace[2076981155] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"213.860033ms","start":"2026-04-11T17:49:44.641255Z","end":"2026-04-11T17:49:44.855115Z","steps":["trace[2076981155] 'process raft request' (duration: 23.624357ms)","trace[2076981155] 'compare' (duration: 189.648144ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:44.855224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.443327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:44.855262Z","caller":"traceutil/trace.go:171","msg":"trace[552045186] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1419; }","duration":"186.527329ms","start":"2026-04-11T17:49:44.668724Z","end":"2026-04-11T17:49:44.855251Z","steps":["trace[552045186] 'agreement among raft nodes before linearized reading' (duration: 186.460047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:44.855284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.545724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:44.855359Z","caller":"traceutil/trace.go:171","msg":"trace[1928483188] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1419; }","duration":"164.690297ms","start":"2026-04-11T17:49:44.690652Z","end":"2026-04-11T17:49:44.855343Z","steps":["trace[1928483188] 'agreement among raft nodes before linearized reading' (duration: 164.555825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:10.402802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.069102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:50:10.402913Z","caller":"traceutil/trace.go:171","msg":"trace[403346129] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1835; }","duration":"127.245616ms","start":"2026-04-11T17:50:10.275646Z","end":"2026-04-11T17:50:10.402892Z","steps":["trace[403346129] 'count revisions from in-memory index tree' (duration: 126.941049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:10.402921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.511576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d5c8293-e9ef-49c4-8b29-f0e93ea5b9e0\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-11T17:50:10.403035Z","caller":"traceutil/trace.go:171","msg":"trace[977095809] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8d5c8293-e9ef-49c4-8b29-f0e93ea5b9e0; range_end:; response_count:1; response_revision:1835; }","duration":"135.72269ms","start":"2026-04-11T17:50:10.267282Z","end":"2026-04-11T17:50:10.403005Z","steps":["trace[977095809] 'range keys from in-memory index tree' (duration: 135.20869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:10.753192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.731143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:50:10.753284Z","caller":"traceutil/trace.go:171","msg":"trace[2132509769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1836; }","duration":"169.886527ms","start":"2026-04-11T17:50:10.583378Z","end":"2026-04-11T17:50:10.753265Z","steps":["trace[2132509769] 'range keys from in-memory index tree' (duration: 169.62395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:10.753562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.330001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d5c8293-e9ef-49c4-8b29-f0e93ea5b9e0\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-11T17:50:10.753642Z","caller":"traceutil/trace.go:171","msg":"trace[405562973] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8d5c8293-e9ef-49c4-8b29-f0e93ea5b9e0; range_end:; response_count:1; response_revision:1836; }","duration":"132.487334ms","start":"2026-04-11T17:50:10.621138Z","end":"2026-04-11T17:50:10.753626Z","steps":["trace[405562973] 'range keys from in-memory index tree' (duration: 132.096086ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:50.781395Z","caller":"traceutil/trace.go:171","msg":"trace[658643438] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"109.341809ms","start":"2026-04-11T17:50:50.672022Z","end":"2026-04-11T17:50:50.781363Z","steps":["trace[658643438] 'process raft request' (duration: 109.126545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:02.399743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.141416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-11T17:51:02.399889Z","caller":"traceutil/trace.go:171","msg":"trace[2135929692] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2161; }","duration":"173.366081ms","start":"2026-04-11T17:51:02.226499Z","end":"2026-04-11T17:51:02.399865Z","steps":["trace[2135929692] 'range keys from in-memory index tree' (duration: 172.907832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:02.401141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.285317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-11T17:51:02.401265Z","caller":"traceutil/trace.go:171","msg":"trace[804367315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2161; }","duration":"166.47219ms","start":"2026-04-11T17:51:02.234766Z","end":"2026-04-11T17:51:02.401238Z","steps":["trace[804367315] 'range keys from in-memory index tree' (duration: 164.795566ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:02.532814Z","caller":"traceutil/trace.go:171","msg":"trace[581708874] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"126.643824ms","start":"2026-04-11T17:51:02.406118Z","end":"2026-04-11T17:51:02.532762Z","steps":["trace[581708874] 'process raft request' (duration: 126.396019ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:02.808421Z","caller":"traceutil/trace.go:171","msg":"trace[1488710644] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"401.880782ms","start":"2026-04-11T17:51:02.406504Z","end":"2026-04-11T17:51:02.808385Z","steps":["trace[1488710644] 'process raft request' (duration: 395.222105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:02.808596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:02.406467Z","time spent":"402.037935ms","remote":"127.0.0.1:36200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:51:02.813057Z","caller":"traceutil/trace.go:171","msg":"trace[1222250606] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2241; }","duration":"229.39707ms","start":"2026-04-11T17:51:02.583619Z","end":"2026-04-11T17:51:02.813016Z","steps":["trace[1222250606] 'read index received' (duration: 218.133939ms)","trace[1222250606] 'applied index is now lower than readState.Index' (duration: 11.261841ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:02.813139Z","caller":"traceutil/trace.go:171","msg":"trace[498562189] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"248.93476ms","start":"2026-04-11T17:51:02.564164Z","end":"2026-04-11T17:51:02.813099Z","steps":["trace[498562189] 'process raft request' (duration: 248.676184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:02.813544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.90481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:02.813652Z","caller":"traceutil/trace.go:171","msg":"trace[1747465281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2164; }","duration":"230.086193ms","start":"2026-04-11T17:51:02.583538Z","end":"2026-04-11T17:51:02.813624Z","steps":["trace[1747465281] 'agreement among raft nodes before linearized reading' (duration: 229.658785ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:26.538198Z","caller":"traceutil/trace.go:171","msg":"trace[1445958709] linearizableReadLoop","detail":"{readStateIndex:2917; appliedIndex:2916; }","duration":"132.778996ms","start":"2026-04-11T17:52:26.405363Z","end":"2026-04-11T17:52:26.538142Z","steps":["trace[1445958709] 'read index received' (duration: 132.592332ms)","trace[1445958709] 'applied index is now lower than readState.Index' (duration: 184.994µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:52:26.538521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.860585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-11T17:52:26.538585Z","caller":"traceutil/trace.go:171","msg":"trace[150471226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2812; }","duration":"127.971058ms","start":"2026-04-11T17:52:26.4106Z","end":"2026-04-11T17:52:26.538571Z","steps":["trace[150471226] 'agreement among raft nodes before linearized reading' (duration: 127.769914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:52:26.538519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.152103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-11T17:52:26.538815Z","caller":"traceutil/trace.go:171","msg":"trace[1502948212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2812; }","duration":"133.47988ms","start":"2026-04-11T17:52:26.405299Z","end":"2026-04-11T17:52:26.538779Z","steps":["trace[1502948212] 'agreement among raft nodes before linearized reading' (duration: 133.01825ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:26.538258Z","caller":"traceutil/trace.go:171","msg":"trace[1557475888] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"143.200409ms","start":"2026-04-11T17:52:26.395019Z","end":"2026-04-11T17:52:26.53822Z","steps":["trace[1557475888] 'process raft request' (duration: 142.896473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:52:26.941064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.664238ms","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-11T17:52:26.941163Z","caller":"traceutil/trace.go:171","msg":"trace[1524945869] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2814; }","duration":"120.855862ms","start":"2026-04-11T17:52:26.820285Z","end":"2026-04-11T17:52:26.941141Z","steps":["trace[1524945869] 'count revisions from in-memory index tree' (duration: 120.546285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:52:26.941283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.701387ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:52:26.941468Z","caller":"traceutil/trace.go:171","msg":"trace[1581956149] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2814; }","duration":"135.897021ms","start":"2026-04-11T17:52:26.805539Z","end":"2026-04-11T17:52:26.941436Z","steps":["trace[1581956149] 'range keys from in-memory index tree' (duration: 135.679116ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:56:37.850139Z","caller":"traceutil/trace.go:171","msg":"trace[1645759143] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"147.315735ms","start":"2026-04-11T17:56:37.702789Z","end":"2026-04-11T17:56:37.850105Z","steps":["trace[1645759143] 'process raft request' (duration: 147.022798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:56:41.315053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.33605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d990ff3a-f5bf-4b3f-ae29-abcd8f70f1f1\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-04-11T17:56:41.315062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.262348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-04-11T17:56:41.31517Z","caller":"traceutil/trace.go:171","msg":"trace[516299144] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d990ff3a-f5bf-4b3f-ae29-abcd8f70f1f1; range_end:; response_count:1; response_revision:5090; }","duration":"122.520314ms","start":"2026-04-11T17:56:41.192628Z","end":"2026-04-11T17:56:41.315148Z","steps":["trace[516299144] 'range keys from in-memory index tree' (duration: 122.136046ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:56:41.315196Z","caller":"traceutil/trace.go:171","msg":"trace[1540860759] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5090; }","duration":"171.438532ms","start":"2026-04-11T17:56:41.143729Z","end":"2026-04-11T17:56:41.315167Z","steps":["trace[1540860759] 'range keys from in-memory index tree' (duration: 171.030274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:56:41.315052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.54748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:56:41.315328Z","caller":"traceutil/trace.go:171","msg":"trace[254988446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5090; }","duration":"131.845367ms","start":"2026-04-11T17:56:41.183441Z","end":"2026-04-11T17:56:41.315286Z","steps":["trace[254988446] 'range keys from in-memory index tree' (duration: 131.379807ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:57:00.263131Z","caller":"traceutil/trace.go:171","msg":"trace[1981853143] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"193.744396ms","start":"2026-04-11T17:57:00.069356Z","end":"2026-04-11T17:57:00.263101Z","steps":["trace[1981853143] 'process raft request' (duration: 193.628454ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:57:00.263159Z","caller":"traceutil/trace.go:171","msg":"trace[92531439] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"194.051222ms","start":"2026-04-11T17:57:00.069073Z","end":"2026-04-11T17:57:00.263124Z","steps":["trace[92531439] 'process raft request' (duration: 106.391778ms)","trace[92531439] 'compare' (duration: 87.360318ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:57:00.263508Z","caller":"traceutil/trace.go:171","msg":"trace[1974953017] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"194.021922ms","start":"2026-04-11T17:57:00.069454Z","end":"2026-04-11T17:57:00.263476Z","steps":["trace[1974953017] 'process raft request' (duration: 193.597993ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:57:00.263833Z","caller":"traceutil/trace.go:171","msg":"trace[1023363265] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"193.616545ms","start":"2026-04-11T17:57:00.070199Z","end":"2026-04-11T17:57:00.263816Z","steps":["trace[1023363265] 'process raft request' (duration: 193.123824ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:57:00.264006Z","caller":"traceutil/trace.go:171","msg":"trace[2061772267] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"193.118744ms","start":"2026-04-11T17:57:00.070786Z","end":"2026-04-11T17:57:00.263904Z","steps":["trace[2061772267] 'process raft request' (duration: 192.895648ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:57:00.264102Z","caller":"traceutil/trace.go:171","msg":"trace[98375476] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"190.763365ms","start":"2026-04-11T17:57:00.073319Z","end":"2026-04-11T17:57:00.264082Z","steps":["trace[98375476] 'process raft request' (duration: 190.50565ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:57:00.264045Z","caller":"traceutil/trace.go:171","msg":"trace[905929] linearizableReadLoop","detail":"{readStateIndex:5670; appliedIndex:5664; }","duration":"134.246158ms","start":"2026-04-11T17:57:00.12977Z","end":"2026-04-11T17:57:00.264017Z","steps":["trace[905929] 'read index received' (duration: 45.627673ms)","trace[905929] 'applied index is now lower than readState.Index' (duration: 88.615915ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:57:00.264266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.477703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-11T17:57:00.264332Z","caller":"traceutil/trace.go:171","msg":"trace[981675568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5470; }","duration":"134.610106ms","start":"2026-04-11T17:57:00.129705Z","end":"2026-04-11T17:57:00.264315Z","steps":["trace[981675568] 'agreement among raft nodes before linearized reading' (duration: 134.391121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:57:00.264351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.453839ms","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-11T17:57:00.264387Z","caller":"traceutil/trace.go:171","msg":"trace[232274353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5470; }","duration":"128.49356ms","start":"2026-04-11T17:57:00.135882Z","end":"2026-04-11T17:57:00.264376Z","steps":["trace[232274353] 'agreement among raft nodes before linearized reading' (duration: 128.385848ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:57:00.414531Z","caller":"traceutil/trace.go:171","msg":"trace[1964607676] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"143.910685ms","start":"2026-04-11T17:57:00.27059Z","end":"2026-04-11T17:57:00.414501Z","steps":["trace[1964607676] 'process raft request' (duration: 113.012033ms)","trace[1964607676] 'compare' (duration: 30.564766ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:57:10.679575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.618868ms","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-11T17:57:10.679695Z","caller":"traceutil/trace.go:171","msg":"trace[1667391300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5628; }","duration":"116.823902ms","start":"2026-04-11T17:57:10.562846Z","end":"2026-04-11T17:57:10.67967Z","steps":["trace[1667391300] 'range keys from in-memory index tree' (duration: 116.051905ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:57:55.16283Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2968} {"level":"info","ts":"2026-04-11T17:57:55.23907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2968,"took":"74.258338ms","hash":2007166707,"current-db-size-bytes":57180160,"current-db-size":"57 MB","current-db-size-in-use-bytes":33091584,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-11T17:57:55.23915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2007166707,"revision":2968,"compact-revision":-1} {"level":"info","ts":"2026-04-11T18:02:55.171323Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6000} {"level":"info","ts":"2026-04-11T18:02:55.268038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6000,"took":"94.14344ms","hash":115386222,"current-db-size-bytes":57180160,"current-db-size":"57 MB","current-db-size-in-use-bytes":21737472,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-11T18:02:55.268137Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":115386222,"revision":6000,"compact-revision":2968} {"level":"info","ts":"2026-04-11T18:03:06.042859Z","caller":"traceutil/trace.go:171","msg":"trace[20328023] transaction","detail":"{read_only:false; response_revision:8305; number_of_response:1; }","duration":"105.677951ms","start":"2026-04-11T18:03:05.937149Z","end":"2026-04-11T18:03:06.042827Z","steps":["trace[20328023] 'process raft request' (duration: 105.470117ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:03:18.620908Z","caller":"traceutil/trace.go:171","msg":"trace[663512829] linearizableReadLoop","detail":"{readStateIndex:8663; appliedIndex:8661; }","duration":"147.923556ms","start":"2026-04-11T18:03:18.472958Z","end":"2026-04-11T18:03:18.620882Z","steps":["trace[663512829] 'read index received' (duration: 73.748934ms)","trace[663512829] 'applied index is now lower than readState.Index' (duration: 74.172942ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:03:18.620985Z","caller":"traceutil/trace.go:171","msg":"trace[1337384424] transaction","detail":"{read_only:false; response_revision:8369; number_of_response:1; }","duration":"171.782252ms","start":"2026-04-11T18:03:18.449128Z","end":"2026-04-11T18:03:18.62091Z","steps":["trace[1337384424] 'process raft request' (duration: 139.530938ms)","trace[1337384424] 'compare' (duration: 31.960929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T18:03:18.621457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.477276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T18:03:18.621505Z","caller":"traceutil/trace.go:171","msg":"trace[1800858844] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8370; }","duration":"148.569019ms","start":"2026-04-11T18:03:18.472925Z","end":"2026-04-11T18:03:18.621494Z","steps":["trace[1800858844] 'agreement among raft nodes before linearized reading' (duration: 148.053809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:03:19.040555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.758554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T18:03:19.040695Z","caller":"traceutil/trace.go:171","msg":"trace[528923492] linearizableReadLoop","detail":"{readStateIndex:8670; appliedIndex:8668; }","duration":"158.653931ms","start":"2026-04-11T18:03:18.882017Z","end":"2026-04-11T18:03:19.040671Z","steps":["trace[528923492] 'read index received' (duration: 17.787256ms)","trace[528923492] 'applied index is now lower than readState.Index' (duration: 140.865205ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:03:19.040835Z","caller":"traceutil/trace.go:171","msg":"trace[9241831] transaction","detail":"{read_only:false; response_revision:8376; number_of_response:1; }","duration":"171.043857ms","start":"2026-04-11T18:03:18.869759Z","end":"2026-04-11T18:03:19.040803Z","steps":["trace[9241831] 'process raft request' (duration: 67.919016ms)","trace[9241831] 'compare' (duration: 102.57596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T18:03:19.040876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.856184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-11T18:03:19.040947Z","caller":"traceutil/trace.go:171","msg":"trace[240901752] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8376; }","duration":"158.932686ms","start":"2026-04-11T18:03:18.881971Z","end":"2026-04-11T18:03:19.040904Z","steps":["trace[240901752] 'agreement among raft nodes before linearized reading' (duration: 158.765193ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:03:47.559274Z","caller":"traceutil/trace.go:171","msg":"trace[1274519256] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"322.600555ms","start":"2026-04-11T18:03:47.236651Z","end":"2026-04-11T18:03:47.559252Z","steps":["trace[1274519256] 'process raft request' (duration: 322.466783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:03:47.55961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T18:03:47.236633Z","time spent":"322.709798ms","remote":"127.0.0.1:36200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T18:03:47.689491Z","caller":"traceutil/trace.go:171","msg":"trace[1751019179] linearizableReadLoop","detail":"{readStateIndex:8882; appliedIndex:8880; }","duration":"316.572815ms","start":"2026-04-11T18:03:47.372887Z","end":"2026-04-11T18:03:47.689459Z","steps":["trace[1751019179] 'read index received' (duration: 185.727091ms)","trace[1751019179] 'applied index is now lower than readState.Index' (duration: 130.844344ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T18:03:47.690192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.464645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-04-11T18:03:47.690238Z","caller":"traceutil/trace.go:171","msg":"trace[1271434596] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8581; }","duration":"227.586877ms","start":"2026-04-11T18:03:47.46264Z","end":"2026-04-11T18:03:47.690227Z","steps":["trace[1271434596] 'agreement among raft nodes before linearized reading' (duration: 227.374113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:03:47.690433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.435692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rrbf8\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-11T18:03:47.690599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.1313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T18:03:47.69065Z","caller":"traceutil/trace.go:171","msg":"trace[2112582863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8581; }","duration":"110.247472ms","start":"2026-04-11T18:03:47.580389Z","end":"2026-04-11T18:03:47.690637Z","steps":["trace[2112582863] 'agreement among raft nodes before linearized reading' (duration: 110.11057ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:03:47.690538Z","caller":"traceutil/trace.go:171","msg":"trace[472725116] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rrbf8; range_end:; response_count:1; response_revision:8581; }","duration":"317.669197ms","start":"2026-04-11T18:03:47.372836Z","end":"2026-04-11T18:03:47.690505Z","steps":["trace[472725116] 'agreement among raft nodes before linearized reading' (duration: 317.29322ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:03:47.690424Z","caller":"traceutil/trace.go:171","msg":"trace[127284066] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"335.383361ms","start":"2026-04-11T18:03:47.354989Z","end":"2026-04-11T18:03:47.690373Z","steps":["trace[127284066] 'process raft request' (duration: 334.261209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:03:47.691143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T18:03:47.372818Z","time spent":"318.308459ms","remote":"127.0.0.1:36120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-rrbf8\" "} {"level":"warn","ts":"2026-04-11T18:03:47.691288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T18:03:47.354954Z","time spent":"336.201698ms","remote":"127.0.0.1:36200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T18:03:49.410442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.202621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T18:03:49.410516Z","caller":"traceutil/trace.go:171","msg":"trace[453080616] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8593; }","duration":"146.342654ms","start":"2026-04-11T18:03:49.264155Z","end":"2026-04-11T18:03:49.410498Z","steps":["trace[453080616] 'range keys from in-memory index tree' (duration: 146.081648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:03:59.920655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.037659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T18:03:59.920816Z","caller":"traceutil/trace.go:171","msg":"trace[1177693237] linearizableReadLoop","detail":"{readStateIndex:8995; appliedIndex:8994; }","duration":"132.782183ms","start":"2026-04-11T18:03:59.788012Z","end":"2026-04-11T18:03:59.920794Z","steps":["trace[1177693237] 'read index received' (duration: 20.412238ms)","trace[1177693237] 'applied index is now lower than readState.Index' (duration: 112.368005ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T18:03:59.921058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.012318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-msbw7\" ","response":"range_response_count:1 size:4638"} {"level":"info","ts":"2026-04-11T18:03:59.921087Z","caller":"traceutil/trace.go:171","msg":"trace[557762292] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"134.222432ms","start":"2026-04-11T18:03:59.786813Z","end":"2026-04-11T18:03:59.921036Z","steps":["trace[557762292] 'process raft request' (duration: 21.672533ms)","trace[557762292] 'compare' (duration: 111.619791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T18:03:59.921134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.354745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-11T18:03:59.921164Z","caller":"traceutil/trace.go:171","msg":"trace[43444757] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-msbw7; range_end:; response_count:1; response_revision:8688; }","duration":"133.14842ms","start":"2026-04-11T18:03:59.787993Z","end":"2026-04-11T18:03:59.921141Z","steps":["trace[43444757] 'agreement among raft nodes before linearized reading' (duration: 132.917866ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:03:59.921187Z","caller":"traceutil/trace.go:171","msg":"trace[955415079] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8688; }","duration":"132.415416ms","start":"2026-04-11T18:03:59.788753Z","end":"2026-04-11T18:03:59.921169Z","steps":["trace[955415079] 'agreement among raft nodes before linearized reading' (duration: 132.171141ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:00.043149Z","caller":"traceutil/trace.go:171","msg":"trace[929683013] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8689; }","duration":"120.328995ms","start":"2026-04-11T18:03:59.922792Z","end":"2026-04-11T18:04:00.043121Z","steps":["trace[929683013] 'process raft request' (duration: 105.414156ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:00.054393Z","caller":"traceutil/trace.go:171","msg":"trace[50450215] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"130.785304ms","start":"2026-04-11T18:03:59.923587Z","end":"2026-04-11T18:04:00.054372Z","steps":["trace[50450215] 'process raft request' (duration: 130.663261ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:00.8254Z","caller":"traceutil/trace.go:171","msg":"trace[381239170] linearizableReadLoop","detail":"{readStateIndex:9001; appliedIndex:9000; }","duration":"214.511627ms","start":"2026-04-11T18:04:00.61086Z","end":"2026-04-11T18:04:00.825372Z","steps":["trace[381239170] 'read index received' (duration: 214.365104ms)","trace[381239170] 'applied index is now lower than readState.Index' (duration: 145.323µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T18:04:00.825465Z","caller":"traceutil/trace.go:171","msg":"trace[1307315752] transaction","detail":"{read_only:false; response_revision:8694; number_of_response:1; }","duration":"214.65358ms","start":"2026-04-11T18:04:00.61077Z","end":"2026-04-11T18:04:00.825424Z","steps":["trace[1307315752] 'process raft request' (duration: 214.432745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:04:00.82583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.932095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-msbw7\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-04-11T18:04:00.825928Z","caller":"traceutil/trace.go:171","msg":"trace[665161350] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-msbw7; range_end:; response_count:1; response_revision:8694; }","duration":"215.091378ms","start":"2026-04-11T18:04:00.610815Z","end":"2026-04-11T18:04:00.825906Z","steps":["trace[665161350] 'agreement among raft nodes before linearized reading' (duration: 214.69334ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:00.855297Z","caller":"traceutil/trace.go:171","msg":"trace[1164486023] transaction","detail":"{read_only:false; response_revision:8695; number_of_response:1; }","duration":"233.119678ms","start":"2026-04-11T18:04:00.62214Z","end":"2026-04-11T18:04:00.85526Z","steps":["trace[1164486023] 'process raft request' (duration: 232.659829ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:01.583063Z","caller":"traceutil/trace.go:171","msg":"trace[1360294912] transaction","detail":"{read_only:false; response_revision:8703; number_of_response:1; }","duration":"253.847422ms","start":"2026-04-11T18:04:01.329183Z","end":"2026-04-11T18:04:01.583031Z","steps":["trace[1360294912] 'process raft request' (duration: 253.651508ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:01.692286Z","caller":"traceutil/trace.go:171","msg":"trace[141684713] linearizableReadLoop","detail":"{readStateIndex:9011; appliedIndex:9009; }","duration":"199.205ms","start":"2026-04-11T18:04:01.49305Z","end":"2026-04-11T18:04:01.692255Z","steps":["trace[141684713] 'read index received' (duration: 89.815505ms)","trace[141684713] 'applied index is now lower than readState.Index' (duration: 109.388265ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T18:04:01.692481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.401784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T18:04:01.692532Z","caller":"traceutil/trace.go:171","msg":"trace[394918013] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:8704; }","duration":"199.526456ms","start":"2026-04-11T18:04:01.492988Z","end":"2026-04-11T18:04:01.692514Z","steps":["trace[394918013] 'agreement among raft nodes before linearized reading' (duration: 199.411904ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:01.692483Z","caller":"traceutil/trace.go:171","msg":"trace[529953208] transaction","detail":"{read_only:false; response_revision:8704; number_of_response:1; }","duration":"242.301741ms","start":"2026-04-11T18:04:01.450112Z","end":"2026-04-11T18:04:01.692414Z","steps":["trace[529953208] 'process raft request' (duration: 241.899013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T18:04:01.692579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.149511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T18:04:01.692684Z","caller":"traceutil/trace.go:171","msg":"trace[305506557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8704; }","duration":"109.297434ms","start":"2026-04-11T18:04:01.58336Z","end":"2026-04-11T18:04:01.692657Z","steps":["trace[305506557] 'agreement among raft nodes before linearized reading' (duration: 109.114911ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:39.575898Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249d.wal"} {"level":"warn","ts":"2026-04-11T18:04:59.95813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.897854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-11T18:04:59.958177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.924845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T18:04:59.958279Z","caller":"traceutil/trace.go:171","msg":"trace[2093139621] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:9326; }","duration":"145.127579ms","start":"2026-04-11T18:04:59.813125Z","end":"2026-04-11T18:04:59.958252Z","steps":["trace[2093139621] 'count revisions from in-memory index tree' (duration: 144.788862ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:04:59.958305Z","caller":"traceutil/trace.go:171","msg":"trace[1713102843] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:9326; }","duration":"164.157999ms","start":"2026-04-11T18:04:59.794121Z","end":"2026-04-11T18:04:59.958279Z","steps":["trace[1713102843] 'count revisions from in-memory index tree' (duration: 163.818402ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T18:05:45.214952Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2062be27cfb43adb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-11T18:05:45.220071Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-11T18:05:45.22019Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-11T18:07:55.181302Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8114} {"level":"info","ts":"2026-04-11T18:07:55.24568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8114,"took":"62.261714ms","hash":2607580885,"current-db-size-bytes":57180160,"current-db-size":"57 MB","current-db-size-in-use-bytes":29110272,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-11T18:07:55.245749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2607580885,"revision":8114,"compact-revision":6000} {"level":"info","ts":"2026-04-11T18:12:55.187565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10646} {"level":"info","ts":"2026-04-11T18:12:55.272935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10646,"took":"83.643413ms","hash":4152896247,"current-db-size-bytes":57180160,"current-db-size":"57 MB","current-db-size-in-use-bytes":21336064,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-11T18:12:55.272993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4152896247,"revision":10646,"compact-revision":8114}