{"level":"warn","ts":"2026-08-31T07:32:39.271293Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T07:32:39.271474Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.197: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.197:2380","--initial-cluster=instance=https://162.253.55.197:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.197:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.197: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-08-31T07:32:39.271809Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T07:32:39.271826Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.197:2380"]} {"level":"info","ts":"2026-08-31T07:32:39.271846Z","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-08-31T07:32:39.272565Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.197:2379"]} {"level":"info","ts":"2026-08-31T07:32:39.272639Z","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.197:2380"],"listen-peer-urls":["https://162.253.55.197:2380"],"advertise-client-urls":["https://162.253.55.197:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.197:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.197: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-08-31T07:32:39.275602Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.756864ms"} {"level":"info","ts":"2026-08-31T07:32:39.281359Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d47da746fe972dd5","cluster-id":"1b4dfb87c8867e00"} {"level":"info","ts":"2026-08-31T07:32:39.281613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d47da746fe972dd5 switched to configuration voters=()"} {"level":"info","ts":"2026-08-31T07:32:39.281665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d47da746fe972dd5 became follower at term 0"} {"level":"info","ts":"2026-08-31T07:32:39.281688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d47da746fe972dd5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-31T07:32:39.281713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d47da746fe972dd5 became follower at term 1"} {"level":"info","ts":"2026-08-31T07:32:39.281782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d47da746fe972dd5 switched to configuration voters=(15311578231490424277)"} {"level":"warn","ts":"2026-08-31T07:32:39.291389Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-31T07:32:39.295456Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-31T07:32:39.29817Z","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-08-31T07:32:39.30171Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d47da746fe972dd5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-31T07:32:39.301891Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d47da746fe972dd5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-31T07:32:39.302101Z","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-08-31T07:32:39.30227Z","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-08-31T07:32:39.302294Z","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-08-31T07:32:39.304034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d47da746fe972dd5 switched to configuration voters=(15311578231490424277)"} {"level":"info","ts":"2026-08-31T07:32:39.3044Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1b4dfb87c8867e00","local-member-id":"d47da746fe972dd5","added-peer-id":"d47da746fe972dd5","added-peer-peer-urls":["https://162.253.55.197:2380"]} {"level":"info","ts":"2026-08-31T07:32:39.309614Z","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-08-31T07:32:39.309707Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.197:2380"} {"level":"info","ts":"2026-08-31T07:32:39.309743Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.197:2380"} {"level":"info","ts":"2026-08-31T07:32:39.310402Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d47da746fe972dd5","initial-advertise-peer-urls":["https://162.253.55.197:2380"],"listen-peer-urls":["https://162.253.55.197:2380"],"advertise-client-urls":["https://162.253.55.197:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.197:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-31T07:32:39.310423Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-31T07:32:39.482099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d47da746fe972dd5 is starting a new election at term 1"} {"level":"info","ts":"2026-08-31T07:32:39.482151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d47da746fe972dd5 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-31T07:32:39.482176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d47da746fe972dd5 received MsgPreVoteResp from d47da746fe972dd5 at term 1"} {"level":"info","ts":"2026-08-31T07:32:39.48219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d47da746fe972dd5 became candidate at term 2"} {"level":"info","ts":"2026-08-31T07:32:39.482197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d47da746fe972dd5 received MsgVoteResp from d47da746fe972dd5 at term 2"} {"level":"info","ts":"2026-08-31T07:32:39.482205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d47da746fe972dd5 became leader at term 2"} {"level":"info","ts":"2026-08-31T07:32:39.482212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d47da746fe972dd5 elected leader d47da746fe972dd5 at term 2"} {"level":"info","ts":"2026-08-31T07:32:39.483331Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:32:39.484253Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d47da746fe972dd5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.197:2379]}","request-path":"/0/members/d47da746fe972dd5/attributes","cluster-id":"1b4dfb87c8867e00","publish-timeout":"7s"} {"level":"info","ts":"2026-08-31T07:32:39.484292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T07:32:39.484746Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1b4dfb87c8867e00","local-member-id":"d47da746fe972dd5","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:32:39.484841Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:32:39.484873Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:32:39.484867Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T07:32:39.485025Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-31T07:32:39.48507Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-31T07:32:39.487127Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-31T07:32:39.488781Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.197:2379"} {"level":"warn","ts":"2026-08-31T07:33:21.235589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.429259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:33:21.236004Z","caller":"traceutil/trace.go:171","msg":"trace[1287226130] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"200.562682ms","start":"2026-08-31T07:33:21.035418Z","end":"2026-08-31T07:33:21.235981Z","steps":["trace[1287226130] 'process raft request' (duration: 200.456889ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:21.236092Z","caller":"traceutil/trace.go:171","msg":"trace[1842196744] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"212.047578ms","start":"2026-08-31T07:33:21.024004Z","end":"2026-08-31T07:33:21.236051Z","steps":["trace[1842196744] 'process raft request' (duration: 211.782781ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:21.236106Z","caller":"traceutil/trace.go:171","msg":"trace[84335882] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"219.916018ms","start":"2026-08-31T07:33:21.016099Z","end":"2026-08-31T07:33:21.236015Z","steps":["trace[84335882] 'process raft request' (duration: 112.400349ms)","trace[84335882] 'compare' (duration: 106.203614ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:33:21.23972Z","caller":"traceutil/trace.go:171","msg":"trace[325956617] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"107.320373ms","start":"2026-08-31T07:33:21.132364Z","end":"2026-08-31T07:33:21.239684Z","steps":["trace[325956617] 'process raft request' (duration: 107.110827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:27.559563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.0429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:33:27.559786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.493299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x5rdf\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-31T07:33:27.559931Z","caller":"traceutil/trace.go:171","msg":"trace[18628035] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x5rdf; range_end:; response_count:1; response_revision:639; }","duration":"116.711605ms","start":"2026-08-31T07:33:27.443195Z","end":"2026-08-31T07:33:27.559906Z","steps":["trace[18628035] 'range keys from in-memory index tree' (duration: 116.275443ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:27.559809Z","caller":"traceutil/trace.go:171","msg":"trace[2050341192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"115.338578ms","start":"2026-08-31T07:33:27.4444Z","end":"2026-08-31T07:33:27.559739Z","steps":["trace[2050341192] 'range keys from in-memory index tree' (duration: 114.977928ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:27.770271Z","caller":"traceutil/trace.go:171","msg":"trace[212758468] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"192.034355ms","start":"2026-08-31T07:33:27.578194Z","end":"2026-08-31T07:33:27.770228Z","steps":["trace[212758468] 'process raft request' (duration: 191.817179ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:28.049233Z","caller":"traceutil/trace.go:171","msg":"trace[2047375108] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"419.275446ms","start":"2026-08-31T07:33:27.629914Z","end":"2026-08-31T07:33:28.049189Z","steps":["trace[2047375108] 'read index received' (duration: 140.092128ms)","trace[2047375108] 'applied index is now lower than readState.Index' (duration: 279.181748ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:33:28.049486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.567324ms","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-08-31T07:33:28.049536Z","caller":"traceutil/trace.go:171","msg":"trace[2037799290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"419.687537ms","start":"2026-08-31T07:33:27.629833Z","end":"2026-08-31T07:33:28.049521Z","steps":["trace[2037799290] 'agreement among raft nodes before linearized reading' (duration: 419.503562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:28.049583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:33:27.629812Z","time spent":"419.76022ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-31T07:33:28.04991Z","caller":"traceutil/trace.go:171","msg":"trace[546375659] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"467.238226ms","start":"2026-08-31T07:33:27.582574Z","end":"2026-08-31T07:33:28.049812Z","steps":["trace[546375659] 'process raft request' (duration: 425.454951ms)","trace[546375659] 'compare' (duration: 40.610393ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:33:28.050195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:33:27.582528Z","time spent":"467.488013ms","remote":"127.0.0.1:54412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:33:28.753455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.397166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:33:28.753972Z","caller":"traceutil/trace.go:171","msg":"trace[205796782] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"652.701635ms","start":"2026-08-31T07:33:28.100987Z","end":"2026-08-31T07:33:28.753689Z","steps":["trace[205796782] 'process raft request' (duration: 50.924739ms)","trace[205796782] 'compare' (duration: 601.15763ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:33:28.754137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.909552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:33:28.754194Z","caller":"traceutil/trace.go:171","msg":"trace[1219660308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"309.024875ms","start":"2026-08-31T07:33:28.445155Z","end":"2026-08-31T07:33:28.75418Z","steps":["trace[1219660308] 'agreement among raft nodes before linearized reading' (duration: 308.907022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:28.754246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:33:28.445125Z","time spent":"309.112166ms","remote":"127.0.0.1:54330","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-08-31T07:33:28.754321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:33:28.100965Z","time spent":"653.129366ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:33:28.754016Z","caller":"traceutil/trace.go:171","msg":"trace[1285923784] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:663; }","duration":"308.753319ms","start":"2026-08-31T07:33:28.445233Z","end":"2026-08-31T07:33:28.753986Z","steps":["trace[1285923784] 'read index received' (duration: 50.492µs)","trace[1285923784] 'applied index is now lower than readState.Index' (duration: 308.701126ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:33:28.754377Z","caller":"traceutil/trace.go:171","msg":"trace[1493405173] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"653.324871ms","start":"2026-08-31T07:33:28.101029Z","end":"2026-08-31T07:33:28.754353Z","steps":["trace[1493405173] 'process raft request' (duration: 652.605122ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:33:28.754542Z","caller":"traceutil/trace.go:171","msg":"trace[611650999] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"629.210568ms","start":"2026-08-31T07:33:28.125308Z","end":"2026-08-31T07:33:28.754518Z","steps":["trace[611650999] 'process raft request' (duration: 628.615142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:28.754627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:33:28.125282Z","time spent":"629.30574ms","remote":"127.0.0.1:54872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-31T07:33:28.754697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.424299ms","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-08-31T07:33:28.754752Z","caller":"traceutil/trace.go:171","msg":"trace[31462385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"142.526433ms","start":"2026-08-31T07:33:28.61221Z","end":"2026-08-31T07:33:28.754736Z","steps":["trace[31462385] 'agreement among raft nodes before linearized reading' (duration: 142.344008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:28.754704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:33:28.101004Z","time spent":"653.529927ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:33:45.058042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.626509ms","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-08-31T07:33:45.058169Z","caller":"traceutil/trace.go:171","msg":"trace[860697784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"186.811324ms","start":"2026-08-31T07:33:44.871329Z","end":"2026-08-31T07:33:45.05814Z","steps":["trace[860697784] 'range keys from in-memory index tree' (duration: 186.363352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:45.058198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.873696ms","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-08-31T07:33:45.058342Z","caller":"traceutil/trace.go:171","msg":"trace[293394603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"208.102842ms","start":"2026-08-31T07:33:44.85021Z","end":"2026-08-31T07:33:45.058313Z","steps":["trace[293394603] 'range keys from in-memory index tree' (duration: 207.545957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:45.058349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.55997ms","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-08-31T07:33:45.058489Z","caller":"traceutil/trace.go:171","msg":"trace[1402812698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:808; }","duration":"197.756086ms","start":"2026-08-31T07:33:44.860692Z","end":"2026-08-31T07:33:45.058449Z","steps":["trace[1402812698] 'range keys from in-memory index tree' (duration: 197.313304ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:35:10.808723Z","caller":"traceutil/trace.go:171","msg":"trace[152655231] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"171.849459ms","start":"2026-08-31T07:35:10.636826Z","end":"2026-08-31T07:35:10.808675Z","steps":["trace[152655231] 'process raft request' (duration: 171.591992ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:35:24.061932Z","caller":"traceutil/trace.go:171","msg":"trace[1481734358] linearizableReadLoop","detail":"{readStateIndex:2103; appliedIndex:2102; }","duration":"261.882035ms","start":"2026-08-31T07:35:23.800023Z","end":"2026-08-31T07:35:24.061905Z","steps":["trace[1481734358] 'read index received' (duration: 185.547116ms)","trace[1481734358] 'applied index is now lower than readState.Index' (duration: 76.333919ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:35:24.061958Z","caller":"traceutil/trace.go:171","msg":"trace[1400641702] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"474.184004ms","start":"2026-08-31T07:35:23.587751Z","end":"2026-08-31T07:35:24.061935Z","steps":["trace[1400641702] 'process raft request' (duration: 397.941389ms)","trace[1400641702] 'compare' (duration: 75.995549ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:35:24.062127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:35:23.587723Z","time spent":"474.302598ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:35:24.062175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.158791ms","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-08-31T07:35:24.062198Z","caller":"traceutil/trace.go:171","msg":"trace[556092123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2026; }","duration":"262.222343ms","start":"2026-08-31T07:35:23.799968Z","end":"2026-08-31T07:35:24.06219Z","steps":["trace[556092123] 'agreement among raft nodes before linearized reading' (duration: 262.083949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:35:24.062328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.1455ms","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-08-31T07:35:24.062509Z","caller":"traceutil/trace.go:171","msg":"trace[190809850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2026; }","duration":"250.378387ms","start":"2026-08-31T07:35:23.812122Z","end":"2026-08-31T07:35:24.062501Z","steps":["trace[190809850] 'agreement among raft nodes before linearized reading' (duration: 250.12423ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:35:24.062499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.990746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-hhvlc\" ","response":"range_response_count:1 size:5852"} {"level":"info","ts":"2026-08-31T07:35:24.062605Z","caller":"traceutil/trace.go:171","msg":"trace[1088801766] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-hhvlc; range_end:; response_count:1; response_revision:2026; }","duration":"138.106569ms","start":"2026-08-31T07:35:23.924469Z","end":"2026-08-31T07:35:24.062575Z","steps":["trace[1088801766] 'agreement among raft nodes before linearized reading' (duration: 137.700458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:35:24.062855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.278815ms","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-08-31T07:35:24.062969Z","caller":"traceutil/trace.go:171","msg":"trace[1790871843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2026; }","duration":"259.480561ms","start":"2026-08-31T07:35:23.803463Z","end":"2026-08-31T07:35:24.062943Z","steps":["trace[1790871843] 'agreement among raft nodes before linearized reading' (duration: 258.665529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:36:00.677548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.609331ms","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-08-31T07:36:00.677683Z","caller":"traceutil/trace.go:171","msg":"trace[1124815473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2238; }","duration":"196.785046ms","start":"2026-08-31T07:36:00.480874Z","end":"2026-08-31T07:36:00.677659Z","steps":["trace[1124815473] 'range keys from in-memory index tree' (duration: 196.373905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:36:00.67755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.51144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:36:00.677809Z","caller":"traceutil/trace.go:171","msg":"trace[920377783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2238; }","duration":"232.821668ms","start":"2026-08-31T07:36:00.444964Z","end":"2026-08-31T07:36:00.677786Z","steps":["trace[920377783] 'range keys from in-memory index tree' (duration: 232.370097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:36:00.677548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.871075ms","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-08-31T07:36:00.678077Z","caller":"traceutil/trace.go:171","msg":"trace[765103901] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2238; }","duration":"231.40069ms","start":"2026-08-31T07:36:00.446633Z","end":"2026-08-31T07:36:00.678033Z","steps":["trace[765103901] 'range keys from in-memory index tree' (duration: 230.739312ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:36:00.8342Z","caller":"traceutil/trace.go:171","msg":"trace[454198128] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"151.796553ms","start":"2026-08-31T07:36:00.682118Z","end":"2026-08-31T07:36:00.833915Z","steps":["trace[454198128] 'process raft request' (duration: 151.595528ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:36:00.892567Z","caller":"traceutil/trace.go:171","msg":"trace[1978791699] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"209.972768ms","start":"2026-08-31T07:36:00.682566Z","end":"2026-08-31T07:36:00.892539Z","steps":["trace[1978791699] 'process raft request' (duration: 207.903973ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:36:06.433255Z","caller":"traceutil/trace.go:171","msg":"trace[1531522484] linearizableReadLoop","detail":"{readStateIndex:2354; appliedIndex:2353; }","duration":"135.039756ms","start":"2026-08-31T07:36:06.298189Z","end":"2026-08-31T07:36:06.433229Z","steps":["trace[1531522484] 'read index received' (duration: 127.518435ms)","trace[1531522484] 'applied index is now lower than readState.Index' (duration: 7.520211ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:36:06.433398Z","caller":"traceutil/trace.go:171","msg":"trace[1570334285] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"160.191638ms","start":"2026-08-31T07:36:06.273165Z","end":"2026-08-31T07:36:06.433357Z","steps":["trace[1570334285] 'process raft request' (duration: 152.625187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:36:06.433527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.325994ms","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-08-31T07:36:06.43368Z","caller":"traceutil/trace.go:171","msg":"trace[1980745840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2268; }","duration":"135.442907ms","start":"2026-08-31T07:36:06.29812Z","end":"2026-08-31T07:36:06.433563Z","steps":["trace[1980745840] 'agreement among raft nodes before linearized reading' (duration: 135.238501ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:36:56.731995Z","caller":"traceutil/trace.go:171","msg":"trace[110501236] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"119.129207ms","start":"2026-08-31T07:36:56.612842Z","end":"2026-08-31T07:36:56.731971Z","steps":["trace[110501236] 'process raft request' (duration: 119.001363ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:29.963325Z","caller":"traceutil/trace.go:171","msg":"trace[208867961] transaction","detail":"{read_only:false; response_revision:2913; number_of_response:1; }","duration":"156.026662ms","start":"2026-08-31T07:37:29.807273Z","end":"2026-08-31T07:37:29.9633Z","steps":["trace[208867961] 'process raft request' (duration: 155.777987ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:29.980212Z","caller":"traceutil/trace.go:171","msg":"trace[1388292950] transaction","detail":"{read_only:false; response_revision:2914; number_of_response:1; }","duration":"150.961697ms","start":"2026-08-31T07:37:29.829231Z","end":"2026-08-31T07:37:29.980192Z","steps":["trace[1388292950] 'process raft request' (duration: 150.844694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:37:30.214995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.566479ms","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-08-31T07:37:30.215383Z","caller":"traceutil/trace.go:171","msg":"trace[615058133] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2914; }","duration":"128.9824ms","start":"2026-08-31T07:37:30.086381Z","end":"2026-08-31T07:37:30.215364Z","steps":["trace[615058133] 'range keys from in-memory index tree' (duration: 128.546539ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:30.21573Z","caller":"traceutil/trace.go:171","msg":"trace[551003796] transaction","detail":"{read_only:false; response_revision:2915; number_of_response:1; }","duration":"122.852766ms","start":"2026-08-31T07:37:30.092852Z","end":"2026-08-31T07:37:30.215704Z","steps":["trace[551003796] 'process raft request' (duration: 66.681854ms)","trace[551003796] 'compare' (duration: 55.467154ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:39:56.266504Z","caller":"traceutil/trace.go:171","msg":"trace[674497578] transaction","detail":"{read_only:false; response_revision:3808; number_of_response:1; }","duration":"106.522617ms","start":"2026-08-31T07:39:56.159949Z","end":"2026-08-31T07:39:56.266471Z","steps":["trace[674497578] 'process raft request' (duration: 101.39691ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:39:56.266803Z","caller":"traceutil/trace.go:171","msg":"trace[2094485463] transaction","detail":"{read_only:false; response_revision:3809; number_of_response:1; }","duration":"101.924855ms","start":"2026-08-31T07:39:56.164842Z","end":"2026-08-31T07:39:56.266767Z","steps":["trace[2094485463] 'process raft request' (duration: 101.800732ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:31.974156Z","caller":"traceutil/trace.go:171","msg":"trace[750133568] transaction","detail":"{read_only:false; response_revision:4075; number_of_response:1; }","duration":"120.627265ms","start":"2026-08-31T07:40:31.853511Z","end":"2026-08-31T07:40:31.974138Z","steps":["trace[750133568] 'process raft request' (duration: 120.494641ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:41:58.579089Z","caller":"traceutil/trace.go:171","msg":"trace[863896108] linearizableReadLoop","detail":"{readStateIndex:5256; appliedIndex:5255; }","duration":"103.861379ms","start":"2026-08-31T07:41:58.475196Z","end":"2026-08-31T07:41:58.579058Z","steps":["trace[863896108] 'read index received' (duration: 35.767037ms)","trace[863896108] 'applied index is now lower than readState.Index' (duration: 68.092792ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:41:58.579481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.244959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-31T07:41:58.579657Z","caller":"traceutil/trace.go:171","msg":"trace[2088216259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5063; }","duration":"104.465235ms","start":"2026-08-31T07:41:58.475152Z","end":"2026-08-31T07:41:58.579618Z","steps":["trace[2088216259] 'agreement among raft nodes before linearized reading' (duration: 104.049264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:42:04.436716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.49522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-gwwm5-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-08-31T07:42:04.43683Z","caller":"traceutil/trace.go:171","msg":"trace[405298131] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-gwwm5-tls; range_end:; response_count:1; response_revision:5271; }","duration":"186.649214ms","start":"2026-08-31T07:42:04.250157Z","end":"2026-08-31T07:42:04.436807Z","steps":["trace[405298131] 'range keys from in-memory index tree' (duration: 186.236963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:42:04.436864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.307784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-08-31T07:42:04.436928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.883845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:42:04.436946Z","caller":"traceutil/trace.go:171","msg":"trace[643948664] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5271; }","duration":"100.447907ms","start":"2026-08-31T07:42:04.336478Z","end":"2026-08-31T07:42:04.436926Z","steps":["trace[643948664] 'range keys from in-memory index tree' (duration: 100.031487ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:04.436995Z","caller":"traceutil/trace.go:171","msg":"trace[1910424900] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5271; }","duration":"284.021989ms","start":"2026-08-31T07:42:04.152959Z","end":"2026-08-31T07:42:04.43698Z","steps":["trace[1910424900] 'range keys from in-memory index tree' (duration: 283.758452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:42:04.437163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.948016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-03cdbea9-db9f-4d24-9292-af0742d3f96a\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-31T07:42:04.43735Z","caller":"traceutil/trace.go:171","msg":"trace[806138408] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-03cdbea9-db9f-4d24-9292-af0742d3f96a; range_end:; response_count:1; response_revision:5271; }","duration":"244.181282ms","start":"2026-08-31T07:42:04.193109Z","end":"2026-08-31T07:42:04.437291Z","steps":["trace[806138408] 'range keys from in-memory index tree' (duration: 243.640688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:42:04.43721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.256806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-03cdbea9-db9f-4d24-9292-af0742d3f96a\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-31T07:42:04.437514Z","caller":"traceutil/trace.go:171","msg":"trace[900343549] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-03cdbea9-db9f-4d24-9292-af0742d3f96a; range_end:; response_count:1; response_revision:5271; }","duration":"188.601535ms","start":"2026-08-31T07:42:04.248883Z","end":"2026-08-31T07:42:04.437484Z","steps":["trace[900343549] 'range keys from in-memory index tree' (duration: 187.866596ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:04.880535Z","caller":"traceutil/trace.go:171","msg":"trace[1138386000] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"188.605285ms","start":"2026-08-31T07:42:04.691893Z","end":"2026-08-31T07:42:04.880499Z","steps":["trace[1138386000] 'process raft request' (duration: 149.815707ms)","trace[1138386000] 'compare' (duration: 38.590722ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:42:19.80198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.865932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:42:19.802149Z","caller":"traceutil/trace.go:171","msg":"trace[1461415125] transaction","detail":"{read_only:false; response_revision:5551; number_of_response:1; }","duration":"292.845224ms","start":"2026-08-31T07:42:19.509278Z","end":"2026-08-31T07:42:19.802124Z","steps":["trace[1461415125] 'process raft request' (duration: 155.726205ms)","trace[1461415125] 'compare' (duration: 136.618505ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:42:19.923002Z","caller":"traceutil/trace.go:171","msg":"trace[414675485] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"115.260674ms","start":"2026-08-31T07:42:19.807707Z","end":"2026-08-31T07:42:19.922968Z","steps":["trace[414675485] 'process raft request' (duration: 107.183738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:42:28.754867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.401241ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-31T07:42:28.755008Z","caller":"traceutil/trace.go:171","msg":"trace[123047503] linearizableReadLoop","detail":"{readStateIndex:5965; appliedIndex:5964; }","duration":"254.17462ms","start":"2026-08-31T07:42:28.500811Z","end":"2026-08-31T07:42:28.754985Z","steps":["trace[123047503] 'read index received' (duration: 93.650906ms)","trace[123047503] 'applied index is now lower than readState.Index' (duration: 160.521844ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:42:28.755213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.384085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-04894e9e-5d9a-4603-bbc6-3a424008749a\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-08-31T07:42:28.755285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.848227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-04894e9e-5d9a-4603-bbc6-3a424008749a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-31T07:42:28.755312Z","caller":"traceutil/trace.go:171","msg":"trace[1694539581] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-04894e9e-5d9a-4603-bbc6-3a424008749a; range_end:; response_count:1; response_revision:5752; }","duration":"254.492068ms","start":"2026-08-31T07:42:28.500799Z","end":"2026-08-31T07:42:28.755291Z","steps":["trace[1694539581] 'agreement among raft nodes before linearized reading' (duration: 254.272842ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:28.755321Z","caller":"traceutil/trace.go:171","msg":"trace[1054546810] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-04894e9e-5d9a-4603-bbc6-3a424008749a; range_end:; response_count:1; response_revision:5752; }","duration":"211.94232ms","start":"2026-08-31T07:42:28.543366Z","end":"2026-08-31T07:42:28.755308Z","steps":["trace[1054546810] 'agreement among raft nodes before linearized reading' (duration: 211.734224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:42:28.755348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.568063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:42:28.755484Z","caller":"traceutil/trace.go:171","msg":"trace[1144713035] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5752; }","duration":"173.771049ms","start":"2026-08-31T07:42:28.581677Z","end":"2026-08-31T07:42:28.755448Z","steps":["trace[1144713035] 'agreement among raft nodes before linearized reading' (duration: 173.545892ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:29.114746Z","caller":"traceutil/trace.go:171","msg":"trace[845222195] linearizableReadLoop","detail":"{readStateIndex:5967; appliedIndex:5966; }","duration":"188.826502ms","start":"2026-08-31T07:42:28.925894Z","end":"2026-08-31T07:42:29.11472Z","steps":["trace[845222195] 'read index received' (duration: 188.615037ms)","trace[845222195] 'applied index is now lower than readState.Index' (duration: 210.005µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:42:29.114824Z","caller":"traceutil/trace.go:171","msg":"trace[1266403901] transaction","detail":"{read_only:false; response_revision:5754; number_of_response:1; }","duration":"251.5904ms","start":"2026-08-31T07:42:28.863194Z","end":"2026-08-31T07:42:29.114784Z","steps":["trace[1266403901] 'process raft request' (duration: 251.306002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:42:29.114993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.087229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-31T07:42:29.115049Z","caller":"traceutil/trace.go:171","msg":"trace[1172812612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5754; }","duration":"189.208382ms","start":"2026-08-31T07:42:28.925829Z","end":"2026-08-31T07:42:29.115038Z","steps":["trace[1172812612] 'agreement among raft nodes before linearized reading' (duration: 189.028207ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:29.2534Z","caller":"traceutil/trace.go:171","msg":"trace[917702917] transaction","detail":"{read_only:false; response_revision:5756; number_of_response:1; }","duration":"131.382764ms","start":"2026-08-31T07:42:29.121986Z","end":"2026-08-31T07:42:29.253369Z","steps":["trace[917702917] 'process raft request' (duration: 126.109473ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:29.25371Z","caller":"traceutil/trace.go:171","msg":"trace[2094392500] transaction","detail":"{read_only:false; response_revision:5757; number_of_response:1; }","duration":"131.60739ms","start":"2026-08-31T07:42:29.122068Z","end":"2026-08-31T07:42:29.253675Z","steps":["trace[2094392500] 'process raft request' (duration: 131.23014ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:29.254029Z","caller":"traceutil/trace.go:171","msg":"trace[2122425408] transaction","detail":"{read_only:false; response_revision:5758; number_of_response:1; }","duration":"131.278192ms","start":"2026-08-31T07:42:29.122733Z","end":"2026-08-31T07:42:29.254011Z","steps":["trace[2122425408] 'process raft request' (duration: 130.798579ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:29.254726Z","caller":"traceutil/trace.go:171","msg":"trace[759450222] transaction","detail":"{read_only:false; response_revision:5761; number_of_response:1; }","duration":"126.130854ms","start":"2026-08-31T07:42:29.128567Z","end":"2026-08-31T07:42:29.254698Z","steps":["trace[759450222] 'process raft request' (duration: 125.912448ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:29.254783Z","caller":"traceutil/trace.go:171","msg":"trace[1184039332] transaction","detail":"{read_only:false; response_revision:5762; number_of_response:1; }","duration":"121.687125ms","start":"2026-08-31T07:42:29.133055Z","end":"2026-08-31T07:42:29.254742Z","steps":["trace[1184039332] 'process raft request' (duration: 121.47568ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:29.255104Z","caller":"traceutil/trace.go:171","msg":"trace[132379739] transaction","detail":"{read_only:false; response_revision:5760; number_of_response:1; }","duration":"126.597737ms","start":"2026-08-31T07:42:29.128492Z","end":"2026-08-31T07:42:29.25509Z","steps":["trace[132379739] 'process raft request' (duration: 125.858097ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:29.255096Z","caller":"traceutil/trace.go:171","msg":"trace[1470107259] transaction","detail":"{read_only:false; response_revision:5759; number_of_response:1; }","duration":"127.164253ms","start":"2026-08-31T07:42:29.127886Z","end":"2026-08-31T07:42:29.25505Z","steps":["trace[1470107259] 'process raft request' (duration: 126.033382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:42:29.256727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.414707ms","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-08-31T07:42:29.256841Z","caller":"traceutil/trace.go:171","msg":"trace[119778730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5762; }","duration":"100.637613ms","start":"2026-08-31T07:42:29.156178Z","end":"2026-08-31T07:42:29.256816Z","steps":["trace[119778730] 'agreement among raft nodes before linearized reading' (duration: 100.238582ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:29.315695Z","caller":"traceutil/trace.go:171","msg":"trace[150984829] transaction","detail":"{read_only:false; response_revision:5764; number_of_response:1; }","duration":"110.329302ms","start":"2026-08-31T07:42:29.205326Z","end":"2026-08-31T07:42:29.315655Z","steps":["trace[150984829] 'process raft request' (duration: 110.232359ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:29.315842Z","caller":"traceutil/trace.go:171","msg":"trace[39631757] transaction","detail":"{read_only:false; response_revision:5763; number_of_response:1; }","duration":"119.634931ms","start":"2026-08-31T07:42:29.196159Z","end":"2026-08-31T07:42:29.315794Z","steps":["trace[39631757] 'process raft request' (duration: 119.169018ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:42:39.906303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2968} {"level":"info","ts":"2026-08-31T07:42:39.994838Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2968,"took":"86.172775ms","hash":1312033270,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":32514048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-31T07:42:39.99493Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1312033270,"revision":2968,"compact-revision":-1} {"level":"info","ts":"2026-08-31T07:47:39.914132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5874} {"level":"info","ts":"2026-08-31T07:47:40.018986Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5874,"took":"102.025228ms","hash":3214188907,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":26824704,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-31T07:47:40.019088Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3214188907,"revision":5874,"compact-revision":2968} {"level":"info","ts":"2026-08-31T07:48:59.308354Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002311.wal"} {"level":"warn","ts":"2026-08-31T07:49:03.891787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.50652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-31T07:49:03.891917Z","caller":"traceutil/trace.go:171","msg":"trace[1565656170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8689; }","duration":"190.705495ms","start":"2026-08-31T07:49:03.701185Z","end":"2026-08-31T07:49:03.89189Z","steps":["trace[1565656170] 'range keys from in-memory index tree' (duration: 190.244962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:49:03.892138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.335736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-31T07:49:03.89223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.449679ms","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-08-31T07:49:03.892235Z","caller":"traceutil/trace.go:171","msg":"trace[1672792705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8689; }","duration":"220.441689ms","start":"2026-08-31T07:49:03.671768Z","end":"2026-08-31T07:49:03.89221Z","steps":["trace[1672792705] 'range keys from in-memory index tree' (duration: 220.070389ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:49:03.892214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.313019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-31T07:49:03.892279Z","caller":"traceutil/trace.go:171","msg":"trace[1958214641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8689; }","duration":"220.535432ms","start":"2026-08-31T07:49:03.671729Z","end":"2026-08-31T07:49:03.892264Z","steps":["trace[1958214641] 'range keys from in-memory index tree' (duration: 220.256644ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:03.892314Z","caller":"traceutil/trace.go:171","msg":"trace[113065139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8689; }","duration":"229.461514ms","start":"2026-08-31T07:49:03.662826Z","end":"2026-08-31T07:49:03.892288Z","steps":["trace[113065139] 'range keys from in-memory index tree' (duration: 229.045912ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:04.061481Z","caller":"traceutil/trace.go:171","msg":"trace[696495425] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"164.375763ms","start":"2026-08-31T07:49:03.897081Z","end":"2026-08-31T07:49:04.061457Z","steps":["trace[696495425] 'process raft request' (duration: 164.111246ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:04.081698Z","caller":"traceutil/trace.go:171","msg":"trace[695828284] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"183.767107ms","start":"2026-08-31T07:49:03.897896Z","end":"2026-08-31T07:49:04.081663Z","steps":["trace[695828284] 'process raft request' (duration: 183.472749ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:04.081957Z","caller":"traceutil/trace.go:171","msg":"trace[761602550] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"183.925172ms","start":"2026-08-31T07:49:03.898008Z","end":"2026-08-31T07:49:04.081933Z","steps":["trace[761602550] 'process raft request' (duration: 183.571263ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:04.082066Z","caller":"traceutil/trace.go:171","msg":"trace[1692767472] transaction","detail":"{read_only:false; response_revision:8693; number_of_response:1; }","duration":"183.599013ms","start":"2026-08-31T07:49:03.898448Z","end":"2026-08-31T07:49:04.082047Z","steps":["trace[1692767472] 'process raft request' (duration: 183.408368ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:04.082092Z","caller":"traceutil/trace.go:171","msg":"trace[2051361163] transaction","detail":"{read_only:false; response_revision:8694; number_of_response:1; }","duration":"182.864414ms","start":"2026-08-31T07:49:03.899195Z","end":"2026-08-31T07:49:04.08206Z","steps":["trace[2051361163] 'process raft request' (duration: 182.776601ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:04.082231Z","caller":"traceutil/trace.go:171","msg":"trace[261201628] transaction","detail":"{read_only:false; response_revision:8695; number_of_response:1; }","duration":"148.605937ms","start":"2026-08-31T07:49:03.933588Z","end":"2026-08-31T07:49:04.082194Z","steps":["trace[261201628] 'process raft request' (duration: 148.426012ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:15.468872Z","caller":"traceutil/trace.go:171","msg":"trace[444474308] transaction","detail":"{read_only:false; response_revision:8751; number_of_response:1; }","duration":"277.85652ms","start":"2026-08-31T07:49:15.190956Z","end":"2026-08-31T07:49:15.468812Z","steps":["trace[444474308] 'process raft request' (duration: 277.538272ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:16.28783Z","caller":"traceutil/trace.go:171","msg":"trace[1791261480] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"116.982952ms","start":"2026-08-31T07:49:16.170817Z","end":"2026-08-31T07:49:16.2878Z","steps":["trace[1791261480] 'process raft request' (duration: 116.727055ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:16.303773Z","caller":"traceutil/trace.go:171","msg":"trace[1939154277] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"111.630877ms","start":"2026-08-31T07:49:16.192108Z","end":"2026-08-31T07:49:16.303739Z","steps":["trace[1939154277] 'process raft request' (duration: 111.35793ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:16.491985Z","caller":"traceutil/trace.go:171","msg":"trace[1333103828] transaction","detail":"{read_only:false; response_revision:8756; number_of_response:1; }","duration":"183.267354ms","start":"2026-08-31T07:49:16.308684Z","end":"2026-08-31T07:49:16.491951Z","steps":["trace[1333103828] 'process raft request' (duration: 183.088649ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:16.498521Z","caller":"traceutil/trace.go:171","msg":"trace[29908390] transaction","detail":"{read_only:false; response_revision:8757; number_of_response:1; }","duration":"189.77194ms","start":"2026-08-31T07:49:16.308729Z","end":"2026-08-31T07:49:16.498501Z","steps":["trace[29908390] 'process raft request' (duration: 189.580705ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:16.498822Z","caller":"traceutil/trace.go:171","msg":"trace[1668831030] transaction","detail":"{read_only:false; response_revision:8758; number_of_response:1; }","duration":"189.695889ms","start":"2026-08-31T07:49:16.309104Z","end":"2026-08-31T07:49:16.4988Z","steps":["trace[1668831030] 'process raft request' (duration: 189.341609ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:16.498929Z","caller":"traceutil/trace.go:171","msg":"trace[1506051724] linearizableReadLoop","detail":"{readStateIndex:9070; appliedIndex:9066; }","duration":"188.989557ms","start":"2026-08-31T07:49:16.309916Z","end":"2026-08-31T07:49:16.498905Z","steps":["trace[1506051724] 'read index received' (duration: 181.978129ms)","trace[1506051724] 'applied index is now lower than readState.Index' (duration: 7.010298ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:49:16.498958Z","caller":"traceutil/trace.go:171","msg":"trace[429525511] transaction","detail":"{read_only:false; response_revision:8759; number_of_response:1; }","duration":"189.521363ms","start":"2026-08-31T07:49:16.309426Z","end":"2026-08-31T07:49:16.498948Z","steps":["trace[429525511] 'process raft request' (duration: 189.269667ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:16.499041Z","caller":"traceutil/trace.go:171","msg":"trace[918200176] transaction","detail":"{read_only:false; response_revision:8760; number_of_response:1; }","duration":"125.153523ms","start":"2026-08-31T07:49:16.373871Z","end":"2026-08-31T07:49:16.499024Z","steps":["trace[918200176] 'process raft request' (duration: 124.913827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:49:16.499129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.181162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:49:16.499443Z","caller":"traceutil/trace.go:171","msg":"trace[1604023698] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:8760; }","duration":"122.546942ms","start":"2026-08-31T07:49:16.376871Z","end":"2026-08-31T07:49:16.499418Z","steps":["trace[1604023698] 'agreement among raft nodes before linearized reading' (duration: 122.172832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:49:16.499477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.529813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:222789"} {"level":"info","ts":"2026-08-31T07:49:16.499569Z","caller":"traceutil/trace.go:171","msg":"trace[640752084] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8760; }","duration":"189.669156ms","start":"2026-08-31T07:49:16.309885Z","end":"2026-08-31T07:49:16.499554Z","steps":["trace[640752084] 'agreement among raft nodes before linearized reading' (duration: 189.163312ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:42.859341Z","caller":"traceutil/trace.go:171","msg":"trace[48374954] linearizableReadLoop","detail":"{readStateIndex:9270; appliedIndex:9269; }","duration":"126.024143ms","start":"2026-08-31T07:49:42.733286Z","end":"2026-08-31T07:49:42.85931Z","steps":["trace[48374954] 'read index received' (duration: 104.404261ms)","trace[48374954] 'applied index is now lower than readState.Index' (duration: 21.618162ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:49:42.85953Z","caller":"traceutil/trace.go:171","msg":"trace[1171713380] transaction","detail":"{read_only:false; response_revision:8951; number_of_response:1; }","duration":"126.358221ms","start":"2026-08-31T07:49:42.733133Z","end":"2026-08-31T07:49:42.859491Z","steps":["trace[1171713380] 'process raft request' (duration: 104.600166ms)","trace[1171713380] 'compare' (duration: 21.243272ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:49:42.859931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.624028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:49:42.859926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.620682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-zpghx\" ","response":"range_response_count:1 size:4455"} {"level":"info","ts":"2026-08-31T07:49:42.859987Z","caller":"traceutil/trace.go:171","msg":"trace[1841383097] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:8951; }","duration":"126.745081ms","start":"2026-08-31T07:49:42.733227Z","end":"2026-08-31T07:49:42.859972Z","steps":["trace[1841383097] 'agreement among raft nodes before linearized reading' (duration: 126.634248ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:42.860011Z","caller":"traceutil/trace.go:171","msg":"trace[1517340338] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-zpghx; range_end:; response_count:1; response_revision:8951; }","duration":"118.766357ms","start":"2026-08-31T07:49:42.741222Z","end":"2026-08-31T07:49:42.859989Z","steps":["trace[1517340338] 'agreement among raft nodes before linearized reading' (duration: 118.484009ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:48.882931Z","caller":"traceutil/trace.go:171","msg":"trace[162822357] transaction","detail":"{read_only:false; response_revision:8984; number_of_response:1; }","duration":"109.94999ms","start":"2026-08-31T07:49:48.772953Z","end":"2026-08-31T07:49:48.882903Z","steps":["trace[162822357] 'process raft request' (duration: 94.089563ms)","trace[162822357] 'compare' (duration: 15.655032ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:49:48.883181Z","caller":"traceutil/trace.go:171","msg":"trace[477741023] transaction","detail":"{read_only:false; response_revision:8985; number_of_response:1; }","duration":"110.064054ms","start":"2026-08-31T07:49:48.773105Z","end":"2026-08-31T07:49:48.883169Z","steps":["trace[477741023] 'process raft request' (duration: 109.754055ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:57.177555Z","caller":"traceutil/trace.go:171","msg":"trace[1201571946] transaction","detail":"{read_only:false; response_revision:9028; number_of_response:1; }","duration":"320.293102ms","start":"2026-08-31T07:49:56.857223Z","end":"2026-08-31T07:49:57.177517Z","steps":["trace[1201571946] 'process raft request' (duration: 279.261868ms)","trace[1201571946] 'compare' (duration: 40.718276ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:49:57.177725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:49:56.857211Z","time spent":"320.418037ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:49:57.239042Z","caller":"traceutil/trace.go:171","msg":"trace[1995016194] linearizableReadLoop","detail":"{readStateIndex:9351; appliedIndex:9349; }","duration":"331.152226ms","start":"2026-08-31T07:49:56.907856Z","end":"2026-08-31T07:49:57.239008Z","steps":["trace[1995016194] 'read index received' (duration: 228.597165ms)","trace[1995016194] 'applied index is now lower than readState.Index' (duration: 102.554051ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:49:57.239197Z","caller":"traceutil/trace.go:171","msg":"trace[1716291751] transaction","detail":"{read_only:false; response_revision:9029; number_of_response:1; }","duration":"373.992318ms","start":"2026-08-31T07:49:56.865168Z","end":"2026-08-31T07:49:57.23916Z","steps":["trace[1716291751] 'process raft request' (duration: 373.675769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:49:57.239344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.473865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-08-31T07:49:57.239369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:49:56.865157Z","time spent":"374.130742ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:49:57.239397Z","caller":"traceutil/trace.go:171","msg":"trace[1547931152] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9029; }","duration":"331.602318ms","start":"2026-08-31T07:49:56.907781Z","end":"2026-08-31T07:49:57.239383Z","steps":["trace[1547931152] 'agreement among raft nodes before linearized reading' (duration: 331.386642ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:49:57.239437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:49:56.907752Z","time spent":"331.6759ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-31T07:49:57.239765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.106119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-31T07:49:57.239759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.834402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:49:57.23982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.370961ms","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-08-31T07:49:57.239858Z","caller":"traceutil/trace.go:171","msg":"trace[367611907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9029; }","duration":"313.241963ms","start":"2026-08-31T07:49:56.926589Z","end":"2026-08-31T07:49:57.239831Z","steps":["trace[367611907] 'agreement among raft nodes before linearized reading' (duration: 312.969266ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:57.239874Z","caller":"traceutil/trace.go:171","msg":"trace[700436809] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:9029; }","duration":"102.050358ms","start":"2026-08-31T07:49:57.137798Z","end":"2026-08-31T07:49:57.239848Z","steps":["trace[700436809] 'agreement among raft nodes before linearized reading' (duration: 101.78753ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:49:57.239889Z","caller":"traceutil/trace.go:171","msg":"trace[1254797024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9029; }","duration":"305.501905ms","start":"2026-08-31T07:49:56.934369Z","end":"2026-08-31T07:49:57.239871Z","steps":["trace[1254797024] 'agreement among raft nodes before linearized reading' (duration: 305.267299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:49:57.239906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:49:56.926572Z","time spent":"313.323185ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-31T07:49:57.23993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:49:56.934337Z","time spent":"305.584518ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-31T07:50:00.194153Z","caller":"traceutil/trace.go:171","msg":"trace[1264855742] transaction","detail":"{read_only:false; response_revision:9057; number_of_response:1; }","duration":"182.160644ms","start":"2026-08-31T07:50:00.011942Z","end":"2026-08-31T07:50:00.194103Z","steps":["trace[1264855742] 'process raft request' (duration: 166.972344ms)","trace[1264855742] 'compare' (duration: 15.004105ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:50:12.388067Z","caller":"traceutil/trace.go:171","msg":"trace[417978706] transaction","detail":"{read_only:false; response_revision:9184; number_of_response:1; }","duration":"135.175839ms","start":"2026-08-31T07:50:12.252787Z","end":"2026-08-31T07:50:12.387963Z","steps":["trace[417978706] 'process raft request' (duration: 134.744698ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:51:01.586595Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d47da746fe972dd5","local-member-applied-index":10003,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T07:51:01.59746Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10003} {"level":"info","ts":"2026-08-31T07:51:01.597637Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5003} {"level":"info","ts":"2026-08-31T07:52:39.922284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8021} {"level":"info","ts":"2026-08-31T07:52:40.002812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8021,"took":"77.943826ms","hash":1081189863,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":28344320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-31T07:52:40.002905Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1081189863,"revision":8021,"compact-revision":5874} {"level":"warn","ts":"2026-08-31T07:56:51.520449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.820901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:56:51.520573Z","caller":"traceutil/trace.go:171","msg":"trace[1269498283] linearizableReadLoop","detail":"{readStateIndex:12663; appliedIndex:12662; }","duration":"139.281629ms","start":"2026-08-31T07:56:51.381278Z","end":"2026-08-31T07:56:51.520559Z","steps":["trace[1269498283] 'read index received' (duration: 11.231932ms)","trace[1269498283] 'applied index is now lower than readState.Index' (duration: 128.048437ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:56:51.520645Z","caller":"traceutil/trace.go:171","msg":"trace[2042485244] transaction","detail":"{read_only:false; response_revision:12239; number_of_response:1; }","duration":"142.232358ms","start":"2026-08-31T07:56:51.378402Z","end":"2026-08-31T07:56:51.520635Z","steps":["trace[2042485244] 'process raft request' (duration: 14.14335ms)","trace[2042485244] 'compare' (duration: 127.699807ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:56:51.521434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.012108ms","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-08-31T07:56:51.52155Z","caller":"traceutil/trace.go:171","msg":"trace[557463323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12239; }","duration":"140.280376ms","start":"2026-08-31T07:56:51.381241Z","end":"2026-08-31T07:56:51.521521Z","steps":["trace[557463323] 'agreement among raft nodes before linearized reading' (duration: 139.529685ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:57:11.683229Z","caller":"traceutil/trace.go:171","msg":"trace[1895826057] transaction","detail":"{read_only:false; response_revision:12353; number_of_response:1; }","duration":"177.958807ms","start":"2026-08-31T07:57:11.505233Z","end":"2026-08-31T07:57:11.683192Z","steps":["trace[1895826057] 'process raft request' (duration: 173.624751ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:57:11.683223Z","caller":"traceutil/trace.go:171","msg":"trace[1422144179] linearizableReadLoop","detail":"{readStateIndex:12781; appliedIndex:12780; }","duration":"128.882619ms","start":"2026-08-31T07:57:11.554286Z","end":"2026-08-31T07:57:11.683169Z","steps":["trace[1422144179] 'read index received' (duration: 124.582255ms)","trace[1422144179] 'applied index is now lower than readState.Index' (duration: 4.298684ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:57:11.683382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.044265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:57:11.683466Z","caller":"traceutil/trace.go:171","msg":"trace[1340604096] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12353; }","duration":"129.224559ms","start":"2026-08-31T07:57:11.554221Z","end":"2026-08-31T07:57:11.683445Z","steps":["trace[1340604096] 'agreement among raft nodes before linearized reading' (duration: 129.040344ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:57:11.746697Z","caller":"traceutil/trace.go:171","msg":"trace[1871681432] transaction","detail":"{read_only:false; response_revision:12354; number_of_response:1; }","duration":"140.909752ms","start":"2026-08-31T07:57:11.60572Z","end":"2026-08-31T07:57:11.746629Z","steps":["trace[1871681432] 'process raft request' (duration: 140.607853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:57:11.74672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.624594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:57:11.747004Z","caller":"traceutil/trace.go:171","msg":"trace[1347283874] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12354; }","duration":"154.011004ms","start":"2026-08-31T07:57:11.592959Z","end":"2026-08-31T07:57:11.74697Z","steps":["trace[1347283874] 'agreement among raft nodes before linearized reading' (duration: 153.585002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:57:11.747019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.781822ms","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-08-31T07:57:11.747319Z","caller":"traceutil/trace.go:171","msg":"trace[378105973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12354; }","duration":"121.0651ms","start":"2026-08-31T07:57:11.626142Z","end":"2026-08-31T07:57:11.747208Z","steps":["trace[378105973] 'agreement among raft nodes before linearized reading' (duration: 120.563597ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:57:11.864418Z","caller":"traceutil/trace.go:171","msg":"trace[1909703341] transaction","detail":"{read_only:false; response_revision:12357; number_of_response:1; }","duration":"111.224865ms","start":"2026-08-31T07:57:11.75316Z","end":"2026-08-31T07:57:11.864385Z","steps":["trace[1909703341] 'process raft request' (duration: 110.732962ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:57:11.864418Z","caller":"traceutil/trace.go:171","msg":"trace[1815126747] transaction","detail":"{read_only:false; response_revision:12355; number_of_response:1; }","duration":"151.048865ms","start":"2026-08-31T07:57:11.713322Z","end":"2026-08-31T07:57:11.864371Z","steps":["trace[1815126747] 'process raft request' (duration: 112.326115ms)","trace[1815126747] 'compare' (duration: 37.736044ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:57:11.86442Z","caller":"traceutil/trace.go:171","msg":"trace[701780042] transaction","detail":"{read_only:false; response_revision:12356; number_of_response:1; }","duration":"111.618196ms","start":"2026-08-31T07:57:11.752755Z","end":"2026-08-31T07:57:11.864373Z","steps":["trace[701780042] 'process raft request' (duration: 110.979189ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:57:39.941423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10386} {"level":"info","ts":"2026-08-31T07:57:40.043895Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10386,"took":"98.36223ms","hash":434581805,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":25870336,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-31T07:57:40.044186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":434581805,"revision":10386,"compact-revision":8021} {"level":"info","ts":"2026-08-31T07:58:59.324839Z","caller":"traceutil/trace.go:171","msg":"trace[725001763] transaction","detail":"{read_only:false; response_revision:13074; number_of_response:1; }","duration":"101.335881ms","start":"2026-08-31T07:58:59.223473Z","end":"2026-08-31T07:58:59.324809Z","steps":["trace[725001763] 'process raft request' (duration: 79.049602ms)","trace[725001763] 'compare' (duration: 22.097114ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:02:39.948996Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12552} {"level":"info","ts":"2026-08-31T08:02:40.044029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12552,"took":"92.378119ms","hash":1939184042,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":27582464,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-31T08:02:40.044102Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1939184042,"revision":12552,"compact-revision":10386} {"level":"warn","ts":"2026-08-31T08:03:38.467549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.148141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T08:03:38.467671Z","caller":"traceutil/trace.go:171","msg":"trace[936154890] transaction","detail":"{read_only:false; response_revision:14934; number_of_response:1; }","duration":"326.570292ms","start":"2026-08-31T08:03:38.14107Z","end":"2026-08-31T08:03:38.467641Z","steps":["trace[936154890] 'process raft request' (duration: 164.215765ms)","trace[936154890] 'compare' (duration: 161.957276ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:03:38.467792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:03:38.141044Z","time spent":"326.685945ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T08:03:38.468211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.842617ms","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-08-31T08:03:38.468352Z","caller":"traceutil/trace.go:171","msg":"trace[2085653450] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14934; }","duration":"286.982371ms","start":"2026-08-31T08:03:38.181357Z","end":"2026-08-31T08:03:38.46834Z","steps":["trace[2085653450] 'agreement among raft nodes before linearized reading' (duration: 286.814236ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:03:38.467654Z","caller":"traceutil/trace.go:171","msg":"trace[859447511] linearizableReadLoop","detail":"{readStateIndex:15453; appliedIndex:15452; }","duration":"286.263081ms","start":"2026-08-31T08:03:38.181374Z","end":"2026-08-31T08:03:38.467637Z","steps":["trace[859447511] 'read index received' (duration: 123.903685ms)","trace[859447511] 'applied index is now lower than readState.Index' (duration: 162.357946ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:03:38.469442Z","caller":"traceutil/trace.go:171","msg":"trace[1132589648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14934; }","duration":"100.013913ms","start":"2026-08-31T08:03:38.369411Z","end":"2026-08-31T08:03:38.469425Z","steps":["trace[1132589648] 'agreement among raft nodes before linearized reading' (duration: 99.873259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:03:38.470158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.392303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-31T08:03:38.470231Z","caller":"traceutil/trace.go:171","msg":"trace[344973118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14934; }","duration":"100.484966ms","start":"2026-08-31T08:03:38.369728Z","end":"2026-08-31T08:03:38.470213Z","steps":["trace[344973118] 'agreement among raft nodes before linearized reading' (duration: 100.275851ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:03:55.60242Z","caller":"traceutil/trace.go:171","msg":"trace[1157404073] linearizableReadLoop","detail":"{readStateIndex:15565; appliedIndex:15564; }","duration":"134.789366ms","start":"2026-08-31T08:03:55.467601Z","end":"2026-08-31T08:03:55.602391Z","steps":["trace[1157404073] 'read index received' (duration: 114.79396ms)","trace[1157404073] 'applied index is now lower than readState.Index' (duration: 19.994526ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:03:55.602579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.787733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T08:03:55.602615Z","caller":"traceutil/trace.go:171","msg":"trace[1928959881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15040; }","duration":"157.885846ms","start":"2026-08-31T08:03:55.444718Z","end":"2026-08-31T08:03:55.602604Z","steps":["trace[1928959881] 'agreement among raft nodes before linearized reading' (duration: 157.782733ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:03:55.602872Z","caller":"traceutil/trace.go:171","msg":"trace[1766359753] transaction","detail":"{read_only:false; response_revision:15040; number_of_response:1; }","duration":"151.927736ms","start":"2026-08-31T08:03:55.450932Z","end":"2026-08-31T08:03:55.602859Z","steps":["trace[1766359753] 'process raft request' (duration: 131.465147ms)","trace[1766359753] 'compare' (duration: 19.825551ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:03:55.616164Z","caller":"traceutil/trace.go:171","msg":"trace[361989772] transaction","detail":"{read_only:false; response_revision:15041; number_of_response:1; }","duration":"134.680054ms","start":"2026-08-31T08:03:55.481465Z","end":"2026-08-31T08:03:55.616145Z","steps":["trace[361989772] 'process raft request' (duration: 134.573781ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:20.956089Z","caller":"traceutil/trace.go:171","msg":"trace[874027501] linearizableReadLoop","detail":"{readStateIndex:15902; appliedIndex:15901; }","duration":"184.395207ms","start":"2026-08-31T08:04:20.771667Z","end":"2026-08-31T08:04:20.956063Z","steps":["trace[874027501] 'read index received' (duration: 134.212131ms)","trace[874027501] 'applied index is now lower than readState.Index' (duration: 50.181396ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:20.956068Z","caller":"traceutil/trace.go:171","msg":"trace[1857690509] transaction","detail":"{read_only:false; response_revision:15365; number_of_response:1; }","duration":"200.778357ms","start":"2026-08-31T08:04:20.75525Z","end":"2026-08-31T08:04:20.956028Z","steps":["trace[1857690509] 'process raft request' (duration: 150.620512ms)","trace[1857690509] 'compare' (duration: 49.57948ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:04:20.956518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.699115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa1a286c-b751-4222-a8a9-914d6a751140\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-08-31T08:04:20.956556Z","caller":"traceutil/trace.go:171","msg":"trace[1765560170] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa1a286c-b751-4222-a8a9-914d6a751140; range_end:; response_count:1; response_revision:15366; }","duration":"178.785797ms","start":"2026-08-31T08:04:20.777759Z","end":"2026-08-31T08:04:20.956545Z","steps":["trace[1765560170] 'agreement among raft nodes before linearized reading' (duration: 178.638923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:20.956731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.048516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa1a286c-b751-4222-a8a9-914d6a751140\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-08-31T08:04:20.956806Z","caller":"traceutil/trace.go:171","msg":"trace[1707824326] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa1a286c-b751-4222-a8a9-914d6a751140; range_end:; response_count:1; response_revision:15365; }","duration":"185.160938ms","start":"2026-08-31T08:04:20.77163Z","end":"2026-08-31T08:04:20.956791Z","steps":["trace[1707824326] 'agreement among raft nodes before linearized reading' (duration: 184.4828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:21.343649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.636488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T08:04:21.343894Z","caller":"traceutil/trace.go:171","msg":"trace[794189504] transaction","detail":"{read_only:false; response_revision:15367; number_of_response:1; }","duration":"373.273395ms","start":"2026-08-31T08:04:20.970586Z","end":"2026-08-31T08:04:21.34386Z","steps":["trace[794189504] 'process raft request' (duration: 164.269467ms)","trace[794189504] 'compare' (duration: 208.206607ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:04:21.344098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:04:20.97057Z","time spent":"373.466131ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T08:04:21.344232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.660899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jczsj\" ","response":"range_response_count:1 size:20565"} {"level":"info","ts":"2026-08-31T08:04:21.344299Z","caller":"traceutil/trace.go:171","msg":"trace[1424451186] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-jczsj; range_end:; response_count:1; response_revision:15368; }","duration":"293.778712ms","start":"2026-08-31T08:04:21.050504Z","end":"2026-08-31T08:04:21.344283Z","steps":["trace[1424451186] 'agreement among raft nodes before linearized reading' (duration: 293.467194ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:21.343903Z","caller":"traceutil/trace.go:171","msg":"trace[1514259281] linearizableReadLoop","detail":"{readStateIndex:15904; appliedIndex:15903; }","duration":"293.31163ms","start":"2026-08-31T08:04:21.050549Z","end":"2026-08-31T08:04:21.343861Z","steps":["trace[1514259281] 'read index received' (duration: 84.199049ms)","trace[1514259281] 'applied index is now lower than readState.Index' (duration: 209.110141ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:05:14.682421Z","caller":"traceutil/trace.go:171","msg":"trace[1833542750] transaction","detail":"{read_only:false; response_revision:16037; number_of_response:1; }","duration":"133.645603ms","start":"2026-08-31T08:05:14.548729Z","end":"2026-08-31T08:05:14.682375Z","steps":["trace[1833542750] 'process raft request' (duration: 133.432097ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:38.183205Z","caller":"traceutil/trace.go:171","msg":"trace[1612226473] linearizableReadLoop","detail":"{readStateIndex:16719; appliedIndex:16718; }","duration":"131.945678ms","start":"2026-08-31T08:05:38.051218Z","end":"2026-08-31T08:05:38.183164Z","steps":["trace[1612226473] 'read index received' (duration: 107.627765ms)","trace[1612226473] 'applied index is now lower than readState.Index' (duration: 24.316233ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:05:38.183408Z","caller":"traceutil/trace.go:171","msg":"trace[1634241051] transaction","detail":"{read_only:false; response_revision:16161; number_of_response:1; }","duration":"159.063905ms","start":"2026-08-31T08:05:38.024324Z","end":"2026-08-31T08:05:38.183388Z","steps":["trace[1634241051] 'process raft request' (duration: 134.602759ms)","trace[1634241051] 'compare' (duration: 23.808288ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:05:38.18386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.637236ms","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-08-31T08:05:38.183948Z","caller":"traceutil/trace.go:171","msg":"trace[1361659903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16161; }","duration":"132.8039ms","start":"2026-08-31T08:05:38.051123Z","end":"2026-08-31T08:05:38.183927Z","steps":["trace[1361659903] 'agreement among raft nodes before linearized reading' (duration: 132.575373ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:38.455733Z","caller":"traceutil/trace.go:171","msg":"trace[1611627953] linearizableReadLoop","detail":"{readStateIndex:16720; appliedIndex:16719; }","duration":"263.178216ms","start":"2026-08-31T08:05:38.192513Z","end":"2026-08-31T08:05:38.455691Z","steps":["trace[1611627953] 'read index received' (duration: 262.913629ms)","trace[1611627953] 'applied index is now lower than readState.Index' (duration: 263.017µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:05:38.455778Z","caller":"traceutil/trace.go:171","msg":"trace[563989479] transaction","detail":"{read_only:false; response_revision:16162; number_of_response:1; }","duration":"266.770911ms","start":"2026-08-31T08:05:38.188971Z","end":"2026-08-31T08:05:38.455742Z","steps":["trace[563989479] 'process raft request' (duration: 266.481294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:38.457045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.500632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:77 size:739083"} {"level":"info","ts":"2026-08-31T08:05:38.45715Z","caller":"traceutil/trace.go:171","msg":"trace[842376062] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:77; response_revision:16162; }","duration":"264.698467ms","start":"2026-08-31T08:05:38.192422Z","end":"2026-08-31T08:05:38.45712Z","steps":["trace[842376062] 'agreement among raft nodes before linearized reading' (duration: 263.409032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:38.48214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.250702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T08:05:38.482232Z","caller":"traceutil/trace.go:171","msg":"trace[1624340088] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16163; }","duration":"136.418308ms","start":"2026-08-31T08:05:38.345793Z","end":"2026-08-31T08:05:38.482212Z","steps":["trace[1624340088] 'agreement among raft nodes before linearized reading' (duration: 136.225752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:38.482312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.140524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-cltmg\" ","response":"range_response_count:1 size:22020"} {"level":"warn","ts":"2026-08-31T08:05:38.482397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.274027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-31T08:05:38.482427Z","caller":"traceutil/trace.go:171","msg":"trace[1102017996] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-cltmg; range_end:; response_count:1; response_revision:16163; }","duration":"276.271608ms","start":"2026-08-31T08:05:38.206127Z","end":"2026-08-31T08:05:38.482398Z","steps":["trace[1102017996] 'agreement among raft nodes before linearized reading' (duration: 276.071062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:38.482296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.782573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-31T08:05:38.482477Z","caller":"traceutil/trace.go:171","msg":"trace[1452881342] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:16163; }","duration":"185.37337ms","start":"2026-08-31T08:05:38.29708Z","end":"2026-08-31T08:05:38.482454Z","steps":["trace[1452881342] 'agreement among raft nodes before linearized reading' (duration: 185.086272ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:38.482506Z","caller":"traceutil/trace.go:171","msg":"trace[1687293277] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16163; }","duration":"129.067451ms","start":"2026-08-31T08:05:38.353419Z","end":"2026-08-31T08:05:38.482486Z","steps":["trace[1687293277] 'agreement among raft nodes before linearized reading' (duration: 128.704781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:38.482589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.329931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-31T08:05:38.482705Z","caller":"traceutil/trace.go:171","msg":"trace[1765255269] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16163; }","duration":"131.551477ms","start":"2026-08-31T08:05:38.351133Z","end":"2026-08-31T08:05:38.482685Z","steps":["trace[1765255269] 'agreement among raft nodes before linearized reading' (duration: 131.152787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:38.483068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.56815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jczsj\" ","response":"range_response_count:1 size:20565"} {"level":"info","ts":"2026-08-31T08:05:38.483139Z","caller":"traceutil/trace.go:171","msg":"trace[392184563] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-jczsj; range_end:; response_count:1; response_revision:16163; }","duration":"287.677223ms","start":"2026-08-31T08:05:38.195444Z","end":"2026-08-31T08:05:38.483121Z","steps":["trace[392184563] 'agreement among raft nodes before linearized reading' (duration: 286.544262ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:38.486863Z","caller":"traceutil/trace.go:171","msg":"trace[604390979] transaction","detail":"{read_only:false; response_revision:16163; number_of_response:1; }","duration":"291.952337ms","start":"2026-08-31T08:05:38.194887Z","end":"2026-08-31T08:05:38.486839Z","steps":["trace[604390979] 'process raft request' (duration: 286.885292ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:39.373211Z","caller":"traceutil/trace.go:171","msg":"trace[2072970883] transaction","detail":"{read_only:false; response_revision:16166; number_of_response:1; }","duration":"225.095125ms","start":"2026-08-31T08:05:39.148089Z","end":"2026-08-31T08:05:39.373184Z","steps":["trace[2072970883] 'process raft request' (duration: 224.91077ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:39.37388Z","caller":"traceutil/trace.go:171","msg":"trace[135999926] linearizableReadLoop","detail":"{readStateIndex:16725; appliedIndex:16725; }","duration":"223.179503ms","start":"2026-08-31T08:05:39.150682Z","end":"2026-08-31T08:05:39.373861Z","steps":["trace[135999926] 'read index received' (duration: 223.170583ms)","trace[135999926] 'applied index is now lower than readState.Index' (duration: 7.62µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:05:39.374003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.300067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T08:05:39.374075Z","caller":"traceutil/trace.go:171","msg":"trace[1460913050] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16166; }","duration":"223.40328ms","start":"2026-08-31T08:05:39.150661Z","end":"2026-08-31T08:05:39.374065Z","steps":["trace[1460913050] 'agreement among raft nodes before linearized reading' (duration: 223.283887ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:39.556512Z","caller":"traceutil/trace.go:171","msg":"trace[1994627919] linearizableReadLoop","detail":"{readStateIndex:16726; appliedIndex:16725; }","duration":"182.560035ms","start":"2026-08-31T08:05:39.373924Z","end":"2026-08-31T08:05:39.556484Z","steps":["trace[1994627919] 'read index received' (duration: 180.833179ms)","trace[1994627919] 'applied index is now lower than readState.Index' (duration: 1.725506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:05:39.557289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.359655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T08:05:39.557408Z","caller":"traceutil/trace.go:171","msg":"trace[1070560318] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16167; }","duration":"406.510639ms","start":"2026-08-31T08:05:39.150868Z","end":"2026-08-31T08:05:39.557378Z","steps":["trace[1070560318] 'agreement among raft nodes before linearized reading' (duration: 406.337565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:39.557322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.437847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T08:05:39.557416Z","caller":"traceutil/trace.go:171","msg":"trace[671232759] transaction","detail":"{read_only:false; response_revision:16167; number_of_response:1; }","duration":"406.656264ms","start":"2026-08-31T08:05:39.150732Z","end":"2026-08-31T08:05:39.557388Z","steps":["trace[671232759] 'process raft request' (duration: 404.116726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:39.557468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:39.150853Z","time spent":"406.601711ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"info","ts":"2026-08-31T08:05:39.557498Z","caller":"traceutil/trace.go:171","msg":"trace[247042894] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16167; }","duration":"406.604702ms","start":"2026-08-31T08:05:39.150854Z","end":"2026-08-31T08:05:39.557459Z","steps":["trace[247042894] 'agreement among raft nodes before linearized reading' (duration: 406.408066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:39.557577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:39.150847Z","time spent":"406.703104ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-08-31T08:05:39.557611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:39.150704Z","time spent":"406.800087ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T08:05:39.557624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.688164ms","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-08-31T08:05:39.557774Z","caller":"traceutil/trace.go:171","msg":"trace[519989058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16167; }","duration":"136.872799ms","start":"2026-08-31T08:05:39.420876Z","end":"2026-08-31T08:05:39.557748Z","steps":["trace[519989058] 'agreement among raft nodes before linearized reading' (duration: 136.643033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:39.55774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.19302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-brhxj\" ","response":"range_response_count:1 size:6451"} {"level":"warn","ts":"2026-08-31T08:05:39.557822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.6352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T08:05:39.557873Z","caller":"traceutil/trace.go:171","msg":"trace[1329343094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16167; }","duration":"112.682961ms","start":"2026-08-31T08:05:39.445174Z","end":"2026-08-31T08:05:39.557857Z","steps":["trace[1329343094] 'agreement among raft nodes before linearized reading' (duration: 112.599939ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:39.557871Z","caller":"traceutil/trace.go:171","msg":"trace[293038025] range","detail":"{range_begin:/registry/pods/openstack/keepalived-brhxj; range_end:; response_count:1; response_revision:16167; }","duration":"237.466048ms","start":"2026-08-31T08:05:39.320373Z","end":"2026-08-31T08:05:39.557839Z","steps":["trace[293038025] 'agreement among raft nodes before linearized reading' (duration: 237.015496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:39.558246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.273019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-31T08:05:39.558298Z","caller":"traceutil/trace.go:171","msg":"trace[647459055] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16167; }","duration":"407.375992ms","start":"2026-08-31T08:05:39.150908Z","end":"2026-08-31T08:05:39.558284Z","steps":["trace[647459055] 'agreement among raft nodes before linearized reading' (duration: 406.314304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:39.558331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:39.1509Z","time spent":"407.422793ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5471,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-08-31T08:05:39.560424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.040256ms","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-08-31T08:05:39.560478Z","caller":"traceutil/trace.go:171","msg":"trace[652157822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16167; }","duration":"406.293403ms","start":"2026-08-31T08:05:39.154172Z","end":"2026-08-31T08:05:39.560465Z","steps":["trace[652157822] 'agreement among raft nodes before linearized reading' (duration: 402.873901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:39.560512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:39.154159Z","time spent":"406.343424ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-31T08:07:24.036886Z","caller":"traceutil/trace.go:171","msg":"trace[1311035202] transaction","detail":"{read_only:false; response_revision:16823; number_of_response:1; }","duration":"161.829789ms","start":"2026-08-31T08:07:23.875019Z","end":"2026-08-31T08:07:24.036849Z","steps":["trace[1311035202] 'process raft request' (duration: 111.125919ms)","trace[1311035202] 'compare' (duration: 50.400822ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:07:24.037133Z","caller":"traceutil/trace.go:171","msg":"trace[1796896821] linearizableReadLoop","detail":"{readStateIndex:17405; appliedIndex:17404; }","duration":"127.713465ms","start":"2026-08-31T08:07:23.909332Z","end":"2026-08-31T08:07:24.037045Z","steps":["trace[1796896821] 'read index received' (duration: 76.843391ms)","trace[1796896821] 'applied index is now lower than readState.Index' (duration: 50.865454ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:07:24.037771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.424433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:1 size:7219"} {"level":"info","ts":"2026-08-31T08:07:24.037827Z","caller":"traceutil/trace.go:171","msg":"trace[2099692666] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:1; response_revision:16825; }","duration":"128.544776ms","start":"2026-08-31T08:07:23.909265Z","end":"2026-08-31T08:07:24.03781Z","steps":["trace[2099692666] 'agreement among raft nodes before linearized reading' (duration: 128.039443ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:07:39.959639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14556} {"level":"info","ts":"2026-08-31T08:07:40.068958Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14556,"took":"105.536449ms","hash":3221357294,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":31240192,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-31T08:07:40.069051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3221357294,"revision":14556,"compact-revision":12552} {"level":"warn","ts":"2026-08-31T08:12:19.484017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.655187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-p9m8g\" ","response":"range_response_count:1 size:9710"} {"level":"info","ts":"2026-08-31T08:12:19.484112Z","caller":"traceutil/trace.go:171","msg":"trace[1654416992] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-p9m8g; range_end:; response_count:1; response_revision:19014; }","duration":"119.835962ms","start":"2026-08-31T08:12:19.364255Z","end":"2026-08-31T08:12:19.484091Z","steps":["trace[1654416992] 'range keys from in-memory index tree' (duration: 119.432831ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:12:19.504584Z","caller":"traceutil/trace.go:171","msg":"trace[1987803195] transaction","detail":"{read_only:false; response_revision:19015; number_of_response:1; }","duration":"122.39732ms","start":"2026-08-31T08:12:19.381641Z","end":"2026-08-31T08:12:19.504038Z","steps":["trace[1987803195] 'process raft request' (duration: 122.129113ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:12:20.360138Z","caller":"traceutil/trace.go:171","msg":"trace[657953539] linearizableReadLoop","detail":"{readStateIndex:19670; appliedIndex:19669; }","duration":"240.30889ms","start":"2026-08-31T08:12:20.119801Z","end":"2026-08-31T08:12:20.36011Z","steps":["trace[657953539] 'read index received' (duration: 240.162456ms)","trace[657953539] 'applied index is now lower than readState.Index' (duration: 145.264µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:12:20.360204Z","caller":"traceutil/trace.go:171","msg":"trace[1286449252] transaction","detail":"{read_only:false; response_revision:19016; number_of_response:1; }","duration":"265.024373ms","start":"2026-08-31T08:12:20.095144Z","end":"2026-08-31T08:12:20.360169Z","steps":["trace[1286449252] 'process raft request' (duration: 264.806537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:12:20.360419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.044319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-31T08:12:20.36044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.295532ms","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-08-31T08:12:20.360502Z","caller":"traceutil/trace.go:171","msg":"trace[837663810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19016; }","duration":"129.167982ms","start":"2026-08-31T08:12:20.231298Z","end":"2026-08-31T08:12:20.360466Z","steps":["trace[837663810] 'agreement among raft nodes before linearized reading' (duration: 129.019698ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:12:20.360488Z","caller":"traceutil/trace.go:171","msg":"trace[573300478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19016; }","duration":"230.400784ms","start":"2026-08-31T08:12:20.130075Z","end":"2026-08-31T08:12:20.360476Z","steps":["trace[573300478] 'agreement among raft nodes before linearized reading' (duration: 230.192639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:12:20.360587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.765373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-31T08:12:20.360717Z","caller":"traceutil/trace.go:171","msg":"trace[381826678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19016; }","duration":"240.982349ms","start":"2026-08-31T08:12:20.119705Z","end":"2026-08-31T08:12:20.360687Z","steps":["trace[381826678] 'agreement among raft nodes before linearized reading' (duration: 240.562668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:12:20.360886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.6195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-31T08:12:20.361047Z","caller":"traceutil/trace.go:171","msg":"trace[1271387586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19016; }","duration":"230.920359ms","start":"2026-08-31T08:12:20.130093Z","end":"2026-08-31T08:12:20.361013Z","steps":["trace[1271387586] 'agreement among raft nodes before linearized reading' (duration: 230.326413ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:12:20.66857Z","caller":"traceutil/trace.go:171","msg":"trace[1971081179] transaction","detail":"{read_only:false; response_revision:19021; number_of_response:1; }","duration":"287.131565ms","start":"2026-08-31T08:12:20.381409Z","end":"2026-08-31T08:12:20.668541Z","steps":["trace[1971081179] 'process raft request' (duration: 286.96206ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:12:20.669277Z","caller":"traceutil/trace.go:171","msg":"trace[11804687] linearizableReadLoop","detail":"{readStateIndex:19675; appliedIndex:19675; }","duration":"187.123415ms","start":"2026-08-31T08:12:20.482141Z","end":"2026-08-31T08:12:20.669264Z","steps":["trace[11804687] 'read index received' (duration: 187.118474ms)","trace[11804687] 'applied index is now lower than readState.Index' (duration: 4.231µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:12:20.669588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.449554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-cltmg\" ","response":"range_response_count:1 size:22021"} {"level":"info","ts":"2026-08-31T08:12:20.669632Z","caller":"traceutil/trace.go:171","msg":"trace[1816753260] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-cltmg; range_end:; response_count:1; response_revision:19021; }","duration":"187.583827ms","start":"2026-08-31T08:12:20.482036Z","end":"2026-08-31T08:12:20.66962Z","steps":["trace[1816753260] 'agreement among raft nodes before linearized reading' (duration: 187.30251ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:12:29.327309Z","caller":"traceutil/trace.go:171","msg":"trace[1710676942] transaction","detail":"{read_only:false; response_revision:19185; number_of_response:1; }","duration":"116.305957ms","start":"2026-08-31T08:12:29.210961Z","end":"2026-08-31T08:12:29.327267Z","steps":["trace[1710676942] 'process raft request' (duration: 94.453061ms)","trace[1710676942] 'compare' (duration: 21.552998ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:12:29.332711Z","caller":"traceutil/trace.go:171","msg":"trace[818718580] linearizableReadLoop","detail":"{readStateIndex:19847; appliedIndex:19845; }","duration":"120.721816ms","start":"2026-08-31T08:12:29.211962Z","end":"2026-08-31T08:12:29.332684Z","steps":["trace[818718580] 'read index received' (duration: 93.450045ms)","trace[818718580] 'applied index is now lower than readState.Index' (duration: 27.270531ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:12:29.332835Z","caller":"traceutil/trace.go:171","msg":"trace[1937691467] transaction","detail":"{read_only:false; response_revision:19187; number_of_response:1; }","duration":"115.196347ms","start":"2026-08-31T08:12:29.217578Z","end":"2026-08-31T08:12:29.332774Z","steps":["trace[1937691467] 'process raft request' (duration: 115.008632ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:12:29.332869Z","caller":"traceutil/trace.go:171","msg":"trace[922706960] transaction","detail":"{read_only:false; response_revision:19186; number_of_response:1; }","duration":"121.340021ms","start":"2026-08-31T08:12:29.211467Z","end":"2026-08-31T08:12:29.332807Z","steps":["trace[922706960] 'process raft request' (duration: 120.968671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:12:29.333078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.079086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:12:29.333218Z","caller":"traceutil/trace.go:171","msg":"trace[775849873] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:19187; }","duration":"121.244079ms","start":"2026-08-31T08:12:29.211946Z","end":"2026-08-31T08:12:29.333191Z","steps":["trace[775849873] 'agreement among raft nodes before linearized reading' (duration: 120.93856ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:12:29.756879Z","caller":"traceutil/trace.go:171","msg":"trace[887173373] linearizableReadLoop","detail":"{readStateIndex:19859; appliedIndex:19858; }","duration":"107.903652ms","start":"2026-08-31T08:12:29.648959Z","end":"2026-08-31T08:12:29.756862Z","steps":["trace[887173373] 'read index received' (duration: 107.717877ms)","trace[887173373] 'applied index is now lower than readState.Index' (duration: 185.085µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:12:29.756996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.017235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:12:29.75702Z","caller":"traceutil/trace.go:171","msg":"trace[2078993542] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:19196; }","duration":"108.068787ms","start":"2026-08-31T08:12:29.648943Z","end":"2026-08-31T08:12:29.757011Z","steps":["trace[2078993542] 'agreement among raft nodes before linearized reading' (duration: 108.007556ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:12:29.75695Z","caller":"traceutil/trace.go:171","msg":"trace[2076742204] transaction","detail":"{read_only:false; response_revision:19196; number_of_response:1; }","duration":"110.386969ms","start":"2026-08-31T08:12:29.646519Z","end":"2026-08-31T08:12:29.756906Z","steps":["trace[2076742204] 'process raft request' (duration: 110.221505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:12:29.757349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.104881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-p9m8g\" ","response":"range_response_count:1 size:9710"} {"level":"info","ts":"2026-08-31T08:12:29.757452Z","caller":"traceutil/trace.go:171","msg":"trace[1201652538] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-p9m8g; range_end:; response_count:1; response_revision:19196; }","duration":"104.218274ms","start":"2026-08-31T08:12:29.653217Z","end":"2026-08-31T08:12:29.757435Z","steps":["trace[1201652538] 'agreement among raft nodes before linearized reading' (duration: 103.959787ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:12:39.97014Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16911} {"level":"info","ts":"2026-08-31T08:12:40.081735Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16911,"took":"107.110601ms","hash":4198653430,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":32124928,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-31T08:12:40.081834Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4198653430,"revision":16911,"compact-revision":14556} {"level":"info","ts":"2026-08-31T08:12:48.831044Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d47da746fe972dd5","local-member-applied-index":20004,"local-member-snapshot-index":10003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T08:12:48.839902Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20004} {"level":"info","ts":"2026-08-31T08:12:48.840099Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15004} {"level":"info","ts":"2026-08-31T08:17:39.978105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19285} {"level":"info","ts":"2026-08-31T08:17:40.097335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19285,"took":"114.708911ms","hash":3797152581,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":33722368,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-31T08:17:40.09743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3797152581,"revision":19285,"compact-revision":16911} {"level":"info","ts":"2026-08-31T08:18:16.155879Z","caller":"traceutil/trace.go:171","msg":"trace[962114901] transaction","detail":"{read_only:false; response_revision:21760; number_of_response:1; }","duration":"105.368369ms","start":"2026-08-31T08:18:16.050461Z","end":"2026-08-31T08:18:16.155829Z","steps":["trace[962114901] 'process raft request' (duration: 104.98794ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:18:32.547135Z","caller":"traceutil/trace.go:171","msg":"trace[1531408520] transaction","detail":"{read_only:false; response_revision:21846; number_of_response:1; }","duration":"117.790312ms","start":"2026-08-31T08:18:32.429318Z","end":"2026-08-31T08:18:32.547108Z","steps":["trace[1531408520] 'process raft request' (duration: 117.609148ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:18:32.570169Z","caller":"traceutil/trace.go:171","msg":"trace[2062549884] linearizableReadLoop","detail":"{readStateIndex:22597; appliedIndex:22595; }","duration":"123.608659ms","start":"2026-08-31T08:18:32.446539Z","end":"2026-08-31T08:18:32.570148Z","steps":["trace[2062549884] 'read index received' (duration: 100.434769ms)","trace[2062549884] 'applied index is now lower than readState.Index' (duration: 23.17261ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:18:32.57035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.800423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:18:32.570395Z","caller":"traceutil/trace.go:171","msg":"trace[775713428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21847; }","duration":"123.921687ms","start":"2026-08-31T08:18:32.446459Z","end":"2026-08-31T08:18:32.570381Z","steps":["trace[775713428] 'agreement among raft nodes before linearized reading' (duration: 123.765093ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:18:32.570312Z","caller":"traceutil/trace.go:171","msg":"trace[2106119508] transaction","detail":"{read_only:false; response_revision:21847; number_of_response:1; }","duration":"135.887627ms","start":"2026-08-31T08:18:32.434368Z","end":"2026-08-31T08:18:32.570255Z","steps":["trace[2106119508] 'process raft request' (duration: 135.678301ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:18:33.062452Z","caller":"traceutil/trace.go:171","msg":"trace[731522632] linearizableReadLoop","detail":"{readStateIndex:22598; appliedIndex:22597; }","duration":"270.855829ms","start":"2026-08-31T08:18:32.791568Z","end":"2026-08-31T08:18:33.062424Z","steps":["trace[731522632] 'read index received' (duration: 270.753616ms)","trace[731522632] 'applied index is now lower than readState.Index' (duration: 100.523µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:18:33.062773Z","caller":"traceutil/trace.go:171","msg":"trace[893933355] transaction","detail":"{read_only:false; response_revision:21848; number_of_response:1; }","duration":"327.29643ms","start":"2026-08-31T08:18:32.735466Z","end":"2026-08-31T08:18:33.062762Z","steps":["trace[893933355] 'process raft request' (duration: 326.779416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:18:33.062866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:18:32.735433Z","time spent":"327.366002ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T08:18:33.063065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.508867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-31T08:18:33.063091Z","caller":"traceutil/trace.go:171","msg":"trace[467204171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21848; }","duration":"271.62563ms","start":"2026-08-31T08:18:32.791459Z","end":"2026-08-31T08:18:33.063085Z","steps":["trace[467204171] 'agreement among raft nodes before linearized reading' (duration: 271.531027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:18:33.063536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.696254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-31T08:18:33.063593Z","caller":"traceutil/trace.go:171","msg":"trace[283511720] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21848; }","duration":"258.806837ms","start":"2026-08-31T08:18:32.804775Z","end":"2026-08-31T08:18:33.063582Z","steps":["trace[283511720] 'agreement among raft nodes before linearized reading' (duration: 258.671823ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:18:33.064303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.338947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-31T08:18:33.064439Z","caller":"traceutil/trace.go:171","msg":"trace[327898203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21848; }","duration":"254.500961ms","start":"2026-08-31T08:18:32.809904Z","end":"2026-08-31T08:18:33.064405Z","steps":["trace[327898203] 'agreement among raft nodes before linearized reading' (duration: 253.582277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:18:33.452672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.38885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T08:18:33.452928Z","caller":"traceutil/trace.go:171","msg":"trace[1567109083] transaction","detail":"{read_only:false; response_revision:21852; number_of_response:1; }","duration":"227.495479ms","start":"2026-08-31T08:18:33.225412Z","end":"2026-08-31T08:18:33.452907Z","steps":["trace[1567109083] 'process raft request' (duration: 227.445838ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:18:33.453141Z","caller":"traceutil/trace.go:171","msg":"trace[1239835742] linearizableReadLoop","detail":"{readStateIndex:22601; appliedIndex:22599; }","duration":"321.72022ms","start":"2026-08-31T08:18:33.131408Z","end":"2026-08-31T08:18:33.453128Z","steps":["trace[1239835742] 'read index received' (duration: 5.665831ms)","trace[1239835742] 'applied index is now lower than readState.Index' (duration: 316.053039ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:18:33.45317Z","caller":"traceutil/trace.go:171","msg":"trace[364487602] transaction","detail":"{read_only:false; response_revision:21851; number_of_response:1; }","duration":"384.380748ms","start":"2026-08-31T08:18:33.068746Z","end":"2026-08-31T08:18:33.453127Z","steps":["trace[364487602] 'process raft request' (duration: 384.035809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:18:33.4533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.888594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-31T08:18:33.453333Z","caller":"traceutil/trace.go:171","msg":"trace[2000014498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21852; }","duration":"321.975647ms","start":"2026-08-31T08:18:33.131348Z","end":"2026-08-31T08:18:33.453324Z","steps":["trace[2000014498] 'agreement among raft nodes before linearized reading' (duration: 321.815103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:18:33.453364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:18:33.131321Z","time spent":"322.032289ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-31T08:18:33.453416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:18:33.06869Z","time spent":"384.577963ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T08:18:33.453491Z","caller":"traceutil/trace.go:171","msg":"trace[1445682626] transaction","detail":"{read_only:false; response_revision:21850; number_of_response:1; }","duration":"384.773179ms","start":"2026-08-31T08:18:33.06864Z","end":"2026-08-31T08:18:33.453413Z","steps":["trace[1445682626] 'process raft request' (duration: 68.529975ms)","trace[1445682626] 'compare' (duration: 315.128023ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:18:33.453756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:18:33.068619Z","time spent":"384.981484ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T08:22:39.991587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21576} {"level":"info","ts":"2026-08-31T08:22:40.16647Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21576,"took":"171.514951ms","hash":3813759370,"current-db-size-bytes":56680448,"current-db-size":"57 MB","current-db-size-in-use-bytes":39063552,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-08-31T08:22:40.166544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3813759370,"revision":21576,"compact-revision":19285} {"level":"info","ts":"2026-08-31T08:22:48.754209Z","caller":"traceutil/trace.go:171","msg":"trace[1863890777] linearizableReadLoop","detail":"{readStateIndex:25257; appliedIndex:25256; }","duration":"166.919547ms","start":"2026-08-31T08:22:48.587258Z","end":"2026-08-31T08:22:48.754177Z","steps":["trace[1863890777] 'read index received' (duration: 166.803254ms)","trace[1863890777] 'applied index is now lower than readState.Index' (duration: 114.653µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:22:48.75426Z","caller":"traceutil/trace.go:171","msg":"trace[1478899948] transaction","detail":"{read_only:false; response_revision:24430; number_of_response:1; }","duration":"170.672148ms","start":"2026-08-31T08:22:48.583558Z","end":"2026-08-31T08:22:48.75423Z","steps":["trace[1478899948] 'process raft request' (duration: 170.442603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:22:48.75451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.236025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-31T08:22:48.754552Z","caller":"traceutil/trace.go:171","msg":"trace[443184947] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24430; }","duration":"167.348968ms","start":"2026-08-31T08:22:48.587192Z","end":"2026-08-31T08:22:48.754541Z","steps":["trace[443184947] 'agreement among raft nodes before linearized reading' (duration: 167.146633ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:22:48.797773Z","caller":"traceutil/trace.go:171","msg":"trace[176420733] transaction","detail":"{read_only:false; response_revision:24431; number_of_response:1; }","duration":"136.262687ms","start":"2026-08-31T08:22:48.661484Z","end":"2026-08-31T08:22:48.797747Z","steps":["trace[176420733] 'process raft request' (duration: 135.953029ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:24:20.16383Z","caller":"traceutil/trace.go:171","msg":"trace[1526563134] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"111.32712ms","start":"2026-08-31T08:24:20.052473Z","end":"2026-08-31T08:24:20.1638Z","steps":["trace[1526563134] 'process raft request' (duration: 111.119934ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:24:20.233458Z","caller":"traceutil/trace.go:171","msg":"trace[101349195] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"180.346656ms","start":"2026-08-31T08:24:20.053071Z","end":"2026-08-31T08:24:20.233418Z","steps":["trace[101349195] 'process raft request' (duration: 180.003847ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:24:20.233453Z","caller":"traceutil/trace.go:171","msg":"trace[992553745] linearizableReadLoop","detail":"{readStateIndex:26237; appliedIndex:26234; }","duration":"176.179606ms","start":"2026-08-31T08:24:20.057227Z","end":"2026-08-31T08:24:20.233407Z","steps":["trace[992553745] 'read index received' (duration: 106.415888ms)","trace[992553745] 'applied index is now lower than readState.Index' (duration: 69.761838ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:24:20.233511Z","caller":"traceutil/trace.go:171","msg":"trace[1235913130] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"178.432285ms","start":"2026-08-31T08:24:20.055036Z","end":"2026-08-31T08:24:20.233469Z","steps":["trace[1235913130] 'process raft request' (duration: 178.292442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:24:20.233772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.457153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:24:20.233884Z","caller":"traceutil/trace.go:171","msg":"trace[373842441] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25384; }","duration":"176.70911ms","start":"2026-08-31T08:24:20.057152Z","end":"2026-08-31T08:24:20.233861Z","steps":["trace[373842441] 'agreement among raft nodes before linearized reading' (duration: 176.408302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:24:20.234099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.290642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-db-sync-lc6bx\" ","response":"range_response_count:1 size:6778"} {"level":"info","ts":"2026-08-31T08:24:20.234229Z","caller":"traceutil/trace.go:171","msg":"trace[659059213] range","detail":"{range_begin:/registry/pods/openstack/magnum-db-sync-lc6bx; range_end:; response_count:1; response_revision:25384; }","duration":"153.530449ms","start":"2026-08-31T08:24:20.080667Z","end":"2026-08-31T08:24:20.234197Z","steps":["trace[659059213] 'agreement among raft nodes before linearized reading' (duration: 153.094397ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:25:02.096216Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067dd.wal"} {"level":"info","ts":"2026-08-31T08:27:39.999865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24175} {"level":"info","ts":"2026-08-31T08:27:40.174794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24175,"took":"170.130647ms","hash":1729477973,"current-db-size-bytes":57946112,"current-db-size":"58 MB","current-db-size-in-use-bytes":34705408,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-31T08:27:40.174874Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1729477973,"revision":24175,"compact-revision":21576} {"level":"info","ts":"2026-08-31T08:32:28.270074Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d47da746fe972dd5","local-member-applied-index":30005,"local-member-snapshot-index":20004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T08:32:28.278777Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30005} {"level":"info","ts":"2026-08-31T08:32:28.278889Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25005} {"level":"info","ts":"2026-08-31T08:32:40.009152Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26905} {"level":"info","ts":"2026-08-31T08:32:40.153327Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26905,"took":"139.59494ms","hash":2862232156,"current-db-size-bytes":57946112,"current-db-size":"58 MB","current-db-size-in-use-bytes":29560832,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T08:32:40.153384Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2862232156,"revision":26905,"compact-revision":24175}