{"level":"warn","ts":"2026-08-07T11:29:34.922276Z","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-07T11:29:34.922393Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.176:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.176:2380","--initial-cluster=instance=https://199.19.213.176:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.176:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.176: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-07T11:29:34.922477Z","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-07T11:29:34.922494Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.176:2380"]} {"level":"info","ts":"2026-08-07T11:29:34.922514Z","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-07T11:29:34.923374Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"]} {"level":"info","ts":"2026-08-07T11:29:34.923473Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.176:2380"],"listen-peer-urls":["https://199.19.213.176:2380"],"advertise-client-urls":["https://199.19.213.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.176: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-07T11:29:34.925802Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.072259ms"} {"level":"info","ts":"2026-08-07T11:29:34.937568Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6e120e57916b9bd4","cluster-id":"e644555f29703ff6"} {"level":"info","ts":"2026-08-07T11:29:34.937651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=()"} {"level":"info","ts":"2026-08-07T11:29:34.93767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became follower at term 0"} {"level":"info","ts":"2026-08-07T11:29:34.937689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6e120e57916b9bd4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-07T11:29:34.937712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became follower at term 1"} {"level":"info","ts":"2026-08-07T11:29:34.937757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=(7931417663017556948)"} {"level":"warn","ts":"2026-08-07T11:29:34.943095Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-07T11:29:34.944884Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-07T11:29:34.946347Z","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-07T11:29:34.949952Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6e120e57916b9bd4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-07T11:29:34.95011Z","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-07T11:29:34.950183Z","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-07T11:29:34.950196Z","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-07T11:29:34.950188Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6e120e57916b9bd4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-07T11:29:34.951183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=(7931417663017556948)"} {"level":"info","ts":"2026-08-07T11:29:34.95131Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e644555f29703ff6","local-member-id":"6e120e57916b9bd4","added-peer-id":"6e120e57916b9bd4","added-peer-peer-urls":["https://199.19.213.176:2380"]} {"level":"info","ts":"2026-08-07T11:29:34.956076Z","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-07T11:29:34.956235Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.176:2380"} {"level":"info","ts":"2026-08-07T11:29:34.956292Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.176:2380"} {"level":"info","ts":"2026-08-07T11:29:34.956521Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6e120e57916b9bd4","initial-advertise-peer-urls":["https://199.19.213.176:2380"],"listen-peer-urls":["https://199.19.213.176:2380"],"advertise-client-urls":["https://199.19.213.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-07T11:29:34.956573Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-07T11:29:35.138428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 is starting a new election at term 1"} {"level":"info","ts":"2026-08-07T11:29:35.138532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-07T11:29:35.138565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 received MsgPreVoteResp from 6e120e57916b9bd4 at term 1"} {"level":"info","ts":"2026-08-07T11:29:35.138586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became candidate at term 2"} {"level":"info","ts":"2026-08-07T11:29:35.138598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 received MsgVoteResp from 6e120e57916b9bd4 at term 2"} {"level":"info","ts":"2026-08-07T11:29:35.138614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became leader at term 2"} {"level":"info","ts":"2026-08-07T11:29:35.13863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6e120e57916b9bd4 elected leader 6e120e57916b9bd4 at term 2"} {"level":"info","ts":"2026-08-07T11:29:35.139869Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6e120e57916b9bd4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.176:2379]}","request-path":"/0/members/6e120e57916b9bd4/attributes","cluster-id":"e644555f29703ff6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-07T11:29:35.139934Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-07T11:29:35.13991Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-07T11:29:35.140312Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-07T11:29:35.140382Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-07T11:29:35.140397Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-07T11:29:35.143605Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-07T11:29:35.143701Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.176:2379"} {"level":"info","ts":"2026-08-07T11:29:35.144122Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e644555f29703ff6","local-member-id":"6e120e57916b9bd4","cluster-version":"3.5"} {"level":"info","ts":"2026-08-07T11:29:35.144236Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-07T11:29:35.144276Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-07T11:29:51.49325Z","caller":"traceutil/trace.go:171","msg":"trace[1914653975] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"154.391658ms","start":"2026-08-07T11:29:51.338833Z","end":"2026-08-07T11:29:51.493224Z","steps":["trace[1914653975] 'read index received' (duration: 133.277908ms)","trace[1914653975] 'applied index is now lower than readState.Index' (duration: 21.11265ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T11:29:51.493495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.648912ms","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-07T11:29:51.493565Z","caller":"traceutil/trace.go:171","msg":"trace[1367122056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"154.757375ms","start":"2026-08-07T11:29:51.338795Z","end":"2026-08-07T11:29:51.493552Z","steps":["trace[1367122056] 'agreement among raft nodes before linearized reading' (duration: 154.586032ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:29:51.493459Z","caller":"traceutil/trace.go:171","msg":"trace[1702529171] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"282.852243ms","start":"2026-08-07T11:29:51.21054Z","end":"2026-08-07T11:29:51.493392Z","steps":["trace[1702529171] 'process raft request' (duration: 261.653611ms)","trace[1702529171] 'compare' (duration: 20.767184ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T11:29:51.493701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.833237ms","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-07T11:29:51.493739Z","caller":"traceutil/trace.go:171","msg":"trace[1164632372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:369; }","duration":"151.892869ms","start":"2026-08-07T11:29:51.341837Z","end":"2026-08-07T11:29:51.49373Z","steps":["trace[1164632372] 'agreement among raft nodes before linearized reading' (duration: 151.825448ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:29:51.610095Z","caller":"traceutil/trace.go:171","msg":"trace[2018068533] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"112.785939ms","start":"2026-08-07T11:29:51.497282Z","end":"2026-08-07T11:29:51.610068Z","steps":["trace[2018068533] 'process raft request' (duration: 105.682381ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:29:51.610359Z","caller":"traceutil/trace.go:171","msg":"trace[1573577904] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"111.971664ms","start":"2026-08-07T11:29:51.49836Z","end":"2026-08-07T11:29:51.610331Z","steps":["trace[1573577904] 'process raft request' (duration: 111.650487ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:30:16.877978Z","caller":"traceutil/trace.go:171","msg":"trace[1990711167] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"132.491162ms","start":"2026-08-07T11:30:16.7454Z","end":"2026-08-07T11:30:16.877891Z","steps":["trace[1990711167] 'process raft request' (duration: 131.85461ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:31:57.480117Z","caller":"traceutil/trace.go:171","msg":"trace[1968849539] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"131.901284ms","start":"2026-08-07T11:31:57.348191Z","end":"2026-08-07T11:31:57.480092Z","steps":["trace[1968849539] 'process raft request' (duration: 131.755692ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:32:09.661651Z","caller":"traceutil/trace.go:171","msg":"trace[1873821066] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"120.183555ms","start":"2026-08-07T11:32:09.541443Z","end":"2026-08-07T11:32:09.661627Z","steps":["trace[1873821066] 'process raft request' (duration: 120.027502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:32:52.563639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.002275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T11:32:52.563747Z","caller":"traceutil/trace.go:171","msg":"trace[1186968553] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2198; }","duration":"199.167978ms","start":"2026-08-07T11:32:52.364562Z","end":"2026-08-07T11:32:52.56373Z","steps":["trace[1186968553] 'count revisions from in-memory index tree' (duration: 198.856351ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:32:52.564009Z","caller":"traceutil/trace.go:171","msg":"trace[805930151] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"197.441363ms","start":"2026-08-07T11:32:52.366534Z","end":"2026-08-07T11:32:52.563976Z","steps":["trace[805930151] 'process raft request' (duration: 107.669561ms)","trace[805930151] 'compare' (duration: 89.579858ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T11:32:52.564007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.206469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-07T11:32:52.564203Z","caller":"traceutil/trace.go:171","msg":"trace[1105321514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2198; }","duration":"240.447363ms","start":"2026-08-07T11:32:52.323739Z","end":"2026-08-07T11:32:52.564186Z","steps":["trace[1105321514] 'range keys from in-memory index tree' (duration: 239.771921ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:32:52.571601Z","caller":"traceutil/trace.go:171","msg":"trace[1917315574] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2284; }","duration":"132.906594ms","start":"2026-08-07T11:32:52.438679Z","end":"2026-08-07T11:32:52.571585Z","steps":["trace[1917315574] 'read index received' (duration: 35.540494ms)","trace[1917315574] 'applied index is now lower than readState.Index' (duration: 97.36487ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:32:52.571683Z","caller":"traceutil/trace.go:171","msg":"trace[272587320] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"204.697785ms","start":"2026-08-07T11:32:52.366939Z","end":"2026-08-07T11:32:52.571636Z","steps":["trace[272587320] 'process raft request' (duration: 204.528562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:32:52.571884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.18943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-07T11:32:52.571955Z","caller":"traceutil/trace.go:171","msg":"trace[1413038182] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2200; }","duration":"133.289683ms","start":"2026-08-07T11:32:52.438649Z","end":"2026-08-07T11:32:52.571939Z","steps":["trace[1413038182] 'agreement among raft nodes before linearized reading' (duration: 133.036917ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:32:52.708203Z","caller":"traceutil/trace.go:171","msg":"trace[2042189467] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"218.609956ms","start":"2026-08-07T11:32:52.489572Z","end":"2026-08-07T11:32:52.708182Z","steps":["trace[2042189467] 'process raft request' (duration: 218.453053ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:32:52.708458Z","caller":"traceutil/trace.go:171","msg":"trace[2068517759] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"162.051663ms","start":"2026-08-07T11:32:52.546377Z","end":"2026-08-07T11:32:52.708428Z","steps":["trace[2068517759] 'process raft request' (duration: 161.761748ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:32:52.708493Z","caller":"traceutil/trace.go:171","msg":"trace[1212997039] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2286; }","duration":"136.76321ms","start":"2026-08-07T11:32:52.571708Z","end":"2026-08-07T11:32:52.708471Z","steps":["trace[1212997039] 'read index received' (duration: 136.268301ms)","trace[1212997039] 'applied index is now lower than readState.Index' (duration: 493.899µs)"],"step_count":2} {"level":"warn","ts":"2026-08-07T11:32:52.708664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.23911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T11:32:52.708686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.824652ms","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-07T11:32:52.708683Z","caller":"traceutil/trace.go:171","msg":"trace[1935568011] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"141.036593ms","start":"2026-08-07T11:32:52.567613Z","end":"2026-08-07T11:32:52.708649Z","steps":["trace[1935568011] 'process raft request' (duration: 140.748868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:32:52.708697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.970736ms","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-07T11:32:52.708729Z","caller":"traceutil/trace.go:171","msg":"trace[1309289189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2203; }","duration":"235.928774ms","start":"2026-08-07T11:32:52.47279Z","end":"2026-08-07T11:32:52.708719Z","steps":["trace[1309289189] 'agreement among raft nodes before linearized reading' (duration: 235.75876ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:32:52.708733Z","caller":"traceutil/trace.go:171","msg":"trace[925952853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2203; }","duration":"153.346552ms","start":"2026-08-07T11:32:52.55537Z","end":"2026-08-07T11:32:52.708717Z","steps":["trace[925952853] 'agreement among raft nodes before linearized reading' (duration: 153.162559ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:32:52.708753Z","caller":"traceutil/trace.go:171","msg":"trace[1069640278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2203; }","duration":"234.070648ms","start":"2026-08-07T11:32:52.474672Z","end":"2026-08-07T11:32:52.708743Z","steps":["trace[1069640278] 'agreement among raft nodes before linearized reading' (duration: 233.887675ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:34:01.883072Z","caller":"traceutil/trace.go:171","msg":"trace[797257684] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"173.564318ms","start":"2026-08-07T11:34:01.709477Z","end":"2026-08-07T11:34:01.883041Z","steps":["trace[797257684] 'process raft request' (duration: 173.412016ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:37:30.484746Z","caller":"traceutil/trace.go:171","msg":"trace[1329990331] linearizableReadLoop","detail":"{readStateIndex:4331; appliedIndex:4330; }","duration":"364.782818ms","start":"2026-08-07T11:37:30.119928Z","end":"2026-08-07T11:37:30.484711Z","steps":["trace[1329990331] 'read index received' (duration: 364.478603ms)","trace[1329990331] 'applied index is now lower than readState.Index' (duration: 303.065µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:37:30.485179Z","caller":"traceutil/trace.go:171","msg":"trace[1830999948] transaction","detail":"{read_only:false; response_revision:4160; number_of_response:1; }","duration":"376.142527ms","start":"2026-08-07T11:37:30.109022Z","end":"2026-08-07T11:37:30.485165Z","steps":["trace[1830999948] 'process raft request' (duration: 375.466744ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:37:30.485707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.766537ms","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-07T11:37:30.485764Z","caller":"traceutil/trace.go:171","msg":"trace[635274000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4160; }","duration":"365.87375ms","start":"2026-08-07T11:37:30.119877Z","end":"2026-08-07T11:37:30.485751Z","steps":["trace[635274000] 'agreement among raft nodes before linearized reading' (duration: 365.734746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:37:30.485784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T11:37:30.11985Z","time spent":"365.928721ms","remote":"127.0.0.1:41592","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":"warn","ts":"2026-08-07T11:37:30.485774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T11:37:30.108994Z","time spent":"376.227358ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T11:37:30.486318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.301748ms","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-07T11:37:30.486341Z","caller":"traceutil/trace.go:171","msg":"trace[339812794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4160; }","duration":"366.380159ms","start":"2026-08-07T11:37:30.119956Z","end":"2026-08-07T11:37:30.486336Z","steps":["trace[339812794] 'agreement among raft nodes before linearized reading' (duration: 366.313458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:37:30.486358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T11:37:30.119932Z","time spent":"366.41948ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T11:37:30.487274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.457281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-07T11:37:30.48741Z","caller":"traceutil/trace.go:171","msg":"trace[80890221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4160; }","duration":"367.396989ms","start":"2026-08-07T11:37:30.119982Z","end":"2026-08-07T11:37:30.487379Z","steps":["trace[80890221] 'agreement among raft nodes before linearized reading' (duration: 365.272787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:37:30.487477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T11:37:30.119972Z","time spent":"367.482711ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-07T11:37:56.7701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.598197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T11:37:56.770212Z","caller":"traceutil/trace.go:171","msg":"trace[880904002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4928; }","duration":"214.7707ms","start":"2026-08-07T11:37:56.555418Z","end":"2026-08-07T11:37:56.770188Z","steps":["trace[880904002] 'range keys from in-memory index tree' (duration: 214.487785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:37:56.770439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.890928ms","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-07T11:37:56.770527Z","caller":"traceutil/trace.go:171","msg":"trace[1875563374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4928; }","duration":"109.050952ms","start":"2026-08-07T11:37:56.66146Z","end":"2026-08-07T11:37:56.770511Z","steps":["trace[1875563374] 'range keys from in-memory index tree' (duration: 108.702795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:37:56.770665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.243736ms","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-07T11:37:56.770711Z","caller":"traceutil/trace.go:171","msg":"trace[335908108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4928; }","duration":"109.338108ms","start":"2026-08-07T11:37:56.661363Z","end":"2026-08-07T11:37:56.770701Z","steps":["trace[335908108] 'range keys from in-memory index tree' (duration: 109.067433ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:37:56.786318Z","caller":"traceutil/trace.go:171","msg":"trace[1194663097] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"121.912759ms","start":"2026-08-07T11:37:56.664388Z","end":"2026-08-07T11:37:56.786301Z","steps":["trace[1194663097] 'process raft request' (duration: 121.795637ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:37:56.786395Z","caller":"traceutil/trace.go:171","msg":"trace[1292086668] linearizableReadLoop","detail":"{readStateIndex:5116; appliedIndex:5116; }","duration":"121.314348ms","start":"2026-08-07T11:37:56.665052Z","end":"2026-08-07T11:37:56.786366Z","steps":["trace[1292086668] 'read index received' (duration: 121.302438ms)","trace[1292086668] 'applied index is now lower than readState.Index' (duration: 9.82µs)"],"step_count":2} {"level":"warn","ts":"2026-08-07T11:37:56.786783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.693395ms","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-07T11:37:56.786811Z","caller":"traceutil/trace.go:171","msg":"trace[1557460116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4929; }","duration":"121.793697ms","start":"2026-08-07T11:37:56.665009Z","end":"2026-08-07T11:37:56.786803Z","steps":["trace[1557460116] 'agreement among raft nodes before linearized reading' (duration: 121.525492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:37:56.816047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.038024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728935"} {"level":"info","ts":"2026-08-07T11:37:56.816079Z","caller":"traceutil/trace.go:171","msg":"trace[555874299] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4931; }","duration":"106.121496ms","start":"2026-08-07T11:37:56.709949Z","end":"2026-08-07T11:37:56.816071Z","steps":["trace[555874299] 'agreement among raft nodes before linearized reading' (duration: 105.779339ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:38:10.450802Z","caller":"traceutil/trace.go:171","msg":"trace[1321970737] linearizableReadLoop","detail":"{readStateIndex:5398; appliedIndex:5397; }","duration":"102.1333ms","start":"2026-08-07T11:38:10.348616Z","end":"2026-08-07T11:38:10.45075Z","steps":["trace[1321970737] 'read index received' (duration: 101.780673ms)","trace[1321970737] 'applied index is now lower than readState.Index' (duration: 351.637µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:38:10.450876Z","caller":"traceutil/trace.go:171","msg":"trace[1654709447] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"171.471659ms","start":"2026-08-07T11:38:10.279352Z","end":"2026-08-07T11:38:10.450824Z","steps":["trace[1654709447] 'process raft request' (duration: 171.182684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:38:10.451272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.63369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-qtrxz\" ","response":"range_response_count:1 size:9942"} {"level":"info","ts":"2026-08-07T11:38:10.451332Z","caller":"traceutil/trace.go:171","msg":"trace[369223048] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-qtrxz; range_end:; response_count:1; response_revision:5202; }","duration":"102.762532ms","start":"2026-08-07T11:38:10.348553Z","end":"2026-08-07T11:38:10.451316Z","steps":["trace[369223048] 'agreement among raft nodes before linearized reading' (duration: 102.343563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:38:10.616856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.672649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-07T11:38:10.617015Z","caller":"traceutil/trace.go:171","msg":"trace[1943887824] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"163.320423ms","start":"2026-08-07T11:38:10.45367Z","end":"2026-08-07T11:38:10.616991Z","steps":["trace[1943887824] 'process raft request' (duration: 46.235006ms)","trace[1943887824] 'compare' (duration: 116.395943ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:38:10.673413Z","caller":"traceutil/trace.go:171","msg":"trace[2050500750] linearizableReadLoop","detail":"{readStateIndex:5401; appliedIndex:5399; }","duration":"119.539114ms","start":"2026-08-07T11:38:10.553838Z","end":"2026-08-07T11:38:10.673377Z","steps":["trace[2050500750] 'read index received' (duration: 119.289989ms)","trace[2050500750] 'applied index is now lower than readState.Index' (duration: 247.955µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:38:10.673494Z","caller":"traceutil/trace.go:171","msg":"trace[2146802038] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"211.232013ms","start":"2026-08-07T11:38:10.462215Z","end":"2026-08-07T11:38:10.673447Z","steps":["trace[2146802038] 'process raft request' (duration: 211.09447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:38:10.673633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.752367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T11:38:10.673641Z","caller":"traceutil/trace.go:171","msg":"trace[1824381415] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"216.990993ms","start":"2026-08-07T11:38:10.456623Z","end":"2026-08-07T11:38:10.673614Z","steps":["trace[1824381415] 'process raft request' (duration: 216.496224ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:38:10.673679Z","caller":"traceutil/trace.go:171","msg":"trace[4016167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5205; }","duration":"119.8926ms","start":"2026-08-07T11:38:10.553775Z","end":"2026-08-07T11:38:10.673668Z","steps":["trace[4016167] 'agreement among raft nodes before linearized reading' (duration: 119.768108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:38:18.596582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.705152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13291"} {"level":"info","ts":"2026-08-07T11:38:18.596735Z","caller":"traceutil/trace.go:171","msg":"trace[1770005023] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5418; }","duration":"154.899685ms","start":"2026-08-07T11:38:18.4418Z","end":"2026-08-07T11:38:18.596699Z","steps":["trace[1770005023] 'range keys from in-memory index tree' (duration: 154.549479ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:38:18.597007Z","caller":"traceutil/trace.go:171","msg":"trace[1322195958] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"154.536139ms","start":"2026-08-07T11:38:18.442411Z","end":"2026-08-07T11:38:18.596947Z","steps":["trace[1322195958] 'process raft request' (duration: 138.021785ms)","trace[1322195958] 'compare' (duration: 16.264399ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:38:18.597003Z","caller":"traceutil/trace.go:171","msg":"trace[1535998386] linearizableReadLoop","detail":"{readStateIndex:5619; appliedIndex:5618; }","duration":"149.582614ms","start":"2026-08-07T11:38:18.447374Z","end":"2026-08-07T11:38:18.596957Z","steps":["trace[1535998386] 'read index received' (duration: 133.000359ms)","trace[1535998386] 'applied index is now lower than readState.Index' (duration: 16.568525ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T11:38:18.597324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.932882ms","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-07T11:38:18.597437Z","caller":"traceutil/trace.go:171","msg":"trace[1187768850] 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:5419; }","duration":"150.612325ms","start":"2026-08-07T11:38:18.446793Z","end":"2026-08-07T11:38:18.597405Z","steps":["trace[1187768850] 'agreement among raft nodes before linearized reading' (duration: 150.288028ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:38:18.764803Z","caller":"traceutil/trace.go:171","msg":"trace[1219085191] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"155.576229ms","start":"2026-08-07T11:38:18.609179Z","end":"2026-08-07T11:38:18.764756Z","steps":["trace[1219085191] 'process raft request' (duration: 120.623404ms)","trace[1219085191] 'compare' (duration: 34.662ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:38:18.92355Z","caller":"traceutil/trace.go:171","msg":"trace[1197025832] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"140.261798ms","start":"2026-08-07T11:38:18.783258Z","end":"2026-08-07T11:38:18.92352Z","steps":["trace[1197025832] 'process raft request' (duration: 97.81039ms)","trace[1197025832] 'compare' (duration: 41.681713ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:39:35.786689Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2961} {"level":"info","ts":"2026-08-07T11:39:35.871857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2961,"took":"83.315164ms","hash":3843454314,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":34099200,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-07T11:39:35.871956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3843454314,"revision":2961,"compact-revision":-1} {"level":"warn","ts":"2026-08-07T11:44:35.733803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.446975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T11:44:35.73406Z","caller":"traceutil/trace.go:171","msg":"trace[274161477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8257; }","duration":"178.70372ms","start":"2026-08-07T11:44:35.555259Z","end":"2026-08-07T11:44:35.733962Z","steps":["trace[274161477] 'range keys from in-memory index tree' (duration: 178.253153ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:44:35.868906Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6068} {"level":"info","ts":"2026-08-07T11:44:35.970757Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6068,"took":"99.665564ms","hash":354895756,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":23879680,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-07T11:44:35.970839Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":354895756,"revision":6068,"compact-revision":2961} {"level":"info","ts":"2026-08-07T11:44:47.613298Z","caller":"traceutil/trace.go:171","msg":"trace[2072354320] transaction","detail":"{read_only:false; response_revision:8321; number_of_response:1; }","duration":"195.495618ms","start":"2026-08-07T11:44:47.417773Z","end":"2026-08-07T11:44:47.613268Z","steps":["trace[2072354320] 'process raft request' (duration: 114.15167ms)","trace[2072354320] 'compare' (duration: 81.176525ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:44:47.613588Z","caller":"traceutil/trace.go:171","msg":"trace[2137095706] linearizableReadLoop","detail":"{readStateIndex:8616; appliedIndex:8614; }","duration":"122.791968ms","start":"2026-08-07T11:44:47.490755Z","end":"2026-08-07T11:44:47.613547Z","steps":["trace[2137095706] 'read index received' (duration: 41.101063ms)","trace[2137095706] 'applied index is now lower than readState.Index' (duration: 81.688415ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:44:47.613673Z","caller":"traceutil/trace.go:171","msg":"trace[1524208954] transaction","detail":"{read_only:false; response_revision:8322; number_of_response:1; }","duration":"175.125186ms","start":"2026-08-07T11:44:47.438513Z","end":"2026-08-07T11:44:47.613638Z","steps":["trace[1524208954] 'process raft request' (duration: 174.715259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:44:47.613922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.149164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-07T11:44:47.613976Z","caller":"traceutil/trace.go:171","msg":"trace[1795588217] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8322; }","duration":"123.285456ms","start":"2026-08-07T11:44:47.490678Z","end":"2026-08-07T11:44:47.613963Z","steps":["trace[1795588217] 'agreement among raft nodes before linearized reading' (duration: 123.047452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:44:47.701061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.806468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T11:44:47.70121Z","caller":"traceutil/trace.go:171","msg":"trace[1710601479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8323; }","duration":"146.027733ms","start":"2026-08-07T11:44:47.55516Z","end":"2026-08-07T11:44:47.701188Z","steps":["trace[1710601479] 'agreement among raft nodes before linearized reading' (duration: 145.805729ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:45:11.173899Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002236.wal"} {"level":"warn","ts":"2026-08-07T11:45:15.710451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.503886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T11:45:15.710515Z","caller":"traceutil/trace.go:171","msg":"trace[697692821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8522; }","duration":"155.599937ms","start":"2026-08-07T11:45:15.5549Z","end":"2026-08-07T11:45:15.7105Z","steps":["trace[697692821] 'range keys from in-memory index tree' (duration: 155.449395ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:45:15.710492Z","caller":"traceutil/trace.go:171","msg":"trace[1852374725] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"131.782692ms","start":"2026-08-07T11:45:15.578679Z","end":"2026-08-07T11:45:15.710462Z","steps":["trace[1852374725] 'process raft request' (duration: 101.848584ms)","trace[1852374725] 'compare' (duration: 29.809126ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T11:45:19.855891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.241976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T11:45:19.856088Z","caller":"traceutil/trace.go:171","msg":"trace[2137033457] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"130.371924ms","start":"2026-08-07T11:45:19.725658Z","end":"2026-08-07T11:45:19.85603Z","steps":["trace[2137033457] 'process raft request' (duration: 27.906844ms)","trace[2137033457] 'compare' (duration: 102.138294ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:45:27.464473Z","caller":"traceutil/trace.go:171","msg":"trace[431039181] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8627; }","duration":"236.323771ms","start":"2026-08-07T11:45:27.228121Z","end":"2026-08-07T11:45:27.464444Z","steps":["trace[431039181] 'process raft request' (duration: 151.353985ms)","trace[431039181] 'compare' (duration: 84.841744ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:45:27.514031Z","caller":"traceutil/trace.go:171","msg":"trace[1880611592] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"284.550564ms","start":"2026-08-07T11:45:27.229465Z","end":"2026-08-07T11:45:27.514016Z","steps":["trace[1880611592] 'process raft request' (duration: 284.439811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:45:28.157334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.367999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-vfdsv\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-08-07T11:45:28.157422Z","caller":"traceutil/trace.go:171","msg":"trace[1761317751] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-vfdsv; range_end:; response_count:1; response_revision:8632; }","duration":"125.502282ms","start":"2026-08-07T11:45:28.031899Z","end":"2026-08-07T11:45:28.157401Z","steps":["trace[1761317751] 'range keys from in-memory index tree' (duration: 125.204446ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:45:28.19431Z","caller":"traceutil/trace.go:171","msg":"trace[1845612486] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"162.11112ms","start":"2026-08-07T11:45:28.032182Z","end":"2026-08-07T11:45:28.194293Z","steps":["trace[1845612486] 'process raft request' (duration: 161.952097ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:45:28.205686Z","caller":"traceutil/trace.go:171","msg":"trace[372183448] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"169.235417ms","start":"2026-08-07T11:45:28.036416Z","end":"2026-08-07T11:45:28.205651Z","steps":["trace[372183448] 'process raft request' (duration: 168.980851ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:47:26.3849Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6e120e57916b9bd4","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-07T11:47:26.390365Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-07T11:47:26.390454Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-07T11:49:35.877289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8258} {"level":"info","ts":"2026-08-07T11:49:35.95763Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8258,"took":"78.192926ms","hash":2006225504,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":25075712,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-07T11:49:35.957702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2006225504,"revision":8258,"compact-revision":6068} {"level":"info","ts":"2026-08-07T11:52:25.620665Z","caller":"traceutil/trace.go:171","msg":"trace[1258370713] transaction","detail":"{read_only:false; response_revision:11824; number_of_response:1; }","duration":"127.461724ms","start":"2026-08-07T11:52:25.493174Z","end":"2026-08-07T11:52:25.620635Z","steps":["trace[1258370713] 'process raft request' (duration: 92.712939ms)","trace[1258370713] 'compare' (duration: 34.572221ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:52:25.620985Z","caller":"traceutil/trace.go:171","msg":"trace[542562789] linearizableReadLoop","detail":"{readStateIndex:12235; appliedIndex:12233; }","duration":"119.701664ms","start":"2026-08-07T11:52:25.501183Z","end":"2026-08-07T11:52:25.620885Z","steps":["trace[542562789] 'read index received' (duration: 50.204225ms)","trace[542562789] 'applied index is now lower than readState.Index' (duration: 69.496469ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:52:25.621107Z","caller":"traceutil/trace.go:171","msg":"trace[160502578] transaction","detail":"{read_only:false; response_revision:11825; number_of_response:1; }","duration":"119.595391ms","start":"2026-08-07T11:52:25.501502Z","end":"2026-08-07T11:52:25.621097Z","steps":["trace[160502578] 'process raft request' (duration: 119.094222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:52:25.621222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.02081ms","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-07T11:52:25.62127Z","caller":"traceutil/trace.go:171","msg":"trace[291115339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11827; }","duration":"120.106362ms","start":"2026-08-07T11:52:25.501153Z","end":"2026-08-07T11:52:25.621259Z","steps":["trace[291115339] 'agreement among raft nodes before linearized reading' (duration: 119.961229ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:52:25.621369Z","caller":"traceutil/trace.go:171","msg":"trace[1049015814] transaction","detail":"{read_only:false; response_revision:11826; number_of_response:1; }","duration":"119.786965ms","start":"2026-08-07T11:52:25.501573Z","end":"2026-08-07T11:52:25.62136Z","steps":["trace[1049015814] 'process raft request' (duration: 119.194934ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:52:25.621476Z","caller":"traceutil/trace.go:171","msg":"trace[1227712512] transaction","detail":"{read_only:false; response_revision:11827; number_of_response:1; }","duration":"118.671794ms","start":"2026-08-07T11:52:25.502796Z","end":"2026-08-07T11:52:25.621467Z","steps":["trace[1227712512] 'process raft request' (duration: 118.037232ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:54:35.882785Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10483} {"level":"info","ts":"2026-08-07T11:54:35.960304Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10483,"took":"75.022994ms","hash":1777855254,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":26107904,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-07T11:54:35.96038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1777855254,"revision":10483,"compact-revision":8258} {"level":"info","ts":"2026-08-07T11:59:35.913458Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12702} {"level":"info","ts":"2026-08-07T11:59:36.069332Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12702,"took":"153.011352ms","hash":3156529943,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":27045888,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-07T11:59:36.069428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3156529943,"revision":12702,"compact-revision":10483} {"level":"info","ts":"2026-08-07T11:59:36.708723Z","caller":"traceutil/trace.go:171","msg":"trace[2082384174] linearizableReadLoop","detail":"{readStateIndex:15166; appliedIndex:15165; }","duration":"151.750798ms","start":"2026-08-07T11:59:36.556947Z","end":"2026-08-07T11:59:36.708698Z","steps":["trace[2082384174] 'read index received' (duration: 151.475642ms)","trace[2082384174] 'applied index is now lower than readState.Index' (duration: 274.316µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T11:59:36.709078Z","caller":"traceutil/trace.go:171","msg":"trace[1044101702] transaction","detail":"{read_only:false; response_revision:14652; number_of_response:1; }","duration":"177.375162ms","start":"2026-08-07T11:59:36.531683Z","end":"2026-08-07T11:59:36.709058Z","steps":["trace[1044101702] 'process raft request' (duration: 176.838051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:59:36.709354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.40328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T11:59:36.709408Z","caller":"traceutil/trace.go:171","msg":"trace[1060070271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14652; }","duration":"152.536933ms","start":"2026-08-07T11:59:36.556859Z","end":"2026-08-07T11:59:36.709396Z","steps":["trace[1060070271] 'agreement among raft nodes before linearized reading' (duration: 152.432031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:59:36.709685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.623307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T11:59:36.70975Z","caller":"traceutil/trace.go:171","msg":"trace[1355213291] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:14652; }","duration":"105.7255ms","start":"2026-08-07T11:59:36.604009Z","end":"2026-08-07T11:59:36.709734Z","steps":["trace[1355213291] 'agreement among raft nodes before linearized reading' (duration: 105.631278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T11:59:45.567251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.011314ms","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-07T11:59:45.567353Z","caller":"traceutil/trace.go:171","msg":"trace[86972245] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14812; }","duration":"150.158827ms","start":"2026-08-07T11:59:45.417167Z","end":"2026-08-07T11:59:45.567326Z","steps":["trace[86972245] 'range keys from in-memory index tree' (duration: 149.841281ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T11:59:45.831768Z","caller":"traceutil/trace.go:171","msg":"trace[977109747] transaction","detail":"{read_only:false; response_revision:14813; number_of_response:1; }","duration":"169.243465ms","start":"2026-08-07T11:59:45.662487Z","end":"2026-08-07T11:59:45.831731Z","steps":["trace[977109747] 'process raft request' (duration: 168.671075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:01:19.492276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.964729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-n7p25\" ","response":"range_response_count:1 size:22092"} {"level":"info","ts":"2026-08-07T12:01:19.492378Z","caller":"traceutil/trace.go:171","msg":"trace[1598729603] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-n7p25; range_end:; response_count:1; response_revision:15909; }","duration":"109.095752ms","start":"2026-08-07T12:01:19.383259Z","end":"2026-08-07T12:01:19.492354Z","steps":["trace[1598729603] 'range keys from in-memory index tree' (duration: 108.819487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:01:19.493936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.324035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:80 size:777098"} {"level":"info","ts":"2026-08-07T12:01:19.494058Z","caller":"traceutil/trace.go:171","msg":"trace[712430390] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:80; response_revision:15909; }","duration":"110.483918ms","start":"2026-08-07T12:01:19.383549Z","end":"2026-08-07T12:01:19.494033Z","steps":["trace[712430390] 'range keys from in-memory index tree' (duration: 109.150883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:01:24.666885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.012887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T12:01:24.66698Z","caller":"traceutil/trace.go:171","msg":"trace[365379317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15936; }","duration":"112.196661ms","start":"2026-08-07T12:01:24.554755Z","end":"2026-08-07T12:01:24.666952Z","steps":["trace[365379317] 'range keys from in-memory index tree' (duration: 111.911476ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:01:25.71163Z","caller":"traceutil/trace.go:171","msg":"trace[224412146] transaction","detail":"{read_only:false; response_revision:15939; number_of_response:1; }","duration":"102.882921ms","start":"2026-08-07T12:01:25.608714Z","end":"2026-08-07T12:01:25.711597Z","steps":["trace[224412146] 'process raft request' (duration: 102.715818ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:01:25.938508Z","caller":"traceutil/trace.go:171","msg":"trace[590869263] linearizableReadLoop","detail":"{readStateIndex:16492; appliedIndex:16490; }","duration":"193.347052ms","start":"2026-08-07T12:01:25.745136Z","end":"2026-08-07T12:01:25.938483Z","steps":["trace[590869263] 'read index received' (duration: 107.833197ms)","trace[590869263] 'applied index is now lower than readState.Index' (duration: 85.512455ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T12:01:25.938521Z","caller":"traceutil/trace.go:171","msg":"trace[1834730062] transaction","detail":"{read_only:false; response_revision:15940; number_of_response:1; }","duration":"327.206503ms","start":"2026-08-07T12:01:25.611276Z","end":"2026-08-07T12:01:25.938482Z","steps":["trace[1834730062] 'process raft request' (duration: 241.723309ms)","trace[1834730062] 'compare' (duration: 85.091307ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T12:01:25.938649Z","caller":"traceutil/trace.go:171","msg":"trace[401314729] transaction","detail":"{read_only:false; response_revision:15941; number_of_response:1; }","duration":"325.699083ms","start":"2026-08-07T12:01:25.612939Z","end":"2026-08-07T12:01:25.938638Z","steps":["trace[401314729] 'process raft request' (duration: 325.442128ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:01:25.938685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T12:01:25.611262Z","time spent":"327.341945ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T12:01:25.938759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T12:01:25.612931Z","time spent":"325.745514ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T12:01:25.938948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.610367ms","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-07T12:01:25.939059Z","caller":"traceutil/trace.go:171","msg":"trace[629347333] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15941; }","duration":"193.80781ms","start":"2026-08-07T12:01:25.745228Z","end":"2026-08-07T12:01:25.939036Z","steps":["trace[629347333] 'agreement among raft nodes before linearized reading' (duration: 193.604337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:01:25.939157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.019005ms","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-07T12:01:25.939208Z","caller":"traceutil/trace.go:171","msg":"trace[1269575437] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15941; }","duration":"194.118187ms","start":"2026-08-07T12:01:25.745078Z","end":"2026-08-07T12:01:25.939196Z","steps":["trace[1269575437] 'agreement among raft nodes before linearized reading' (duration: 193.966663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:01:25.939235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.602055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-07T12:01:25.939321Z","caller":"traceutil/trace.go:171","msg":"trace[1652747748] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:15941; }","duration":"103.717157ms","start":"2026-08-07T12:01:25.835583Z","end":"2026-08-07T12:01:25.9393Z","steps":["trace[1652747748] 'agreement among raft nodes before linearized reading' (duration: 103.437111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:01:25.939302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.734639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-08-07T12:01:25.939361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.063059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T12:01:25.93939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.816832ms","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-07T12:01:25.939406Z","caller":"traceutil/trace.go:171","msg":"trace[1870680052] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15941; }","duration":"118.874031ms","start":"2026-08-07T12:01:25.820509Z","end":"2026-08-07T12:01:25.939383Z","steps":["trace[1870680052] 'agreement among raft nodes before linearized reading' (duration: 118.530293ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:01:25.93944Z","caller":"traceutil/trace.go:171","msg":"trace[443410591] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15941; }","duration":"147.889663ms","start":"2026-08-07T12:01:25.791537Z","end":"2026-08-07T12:01:25.939427Z","steps":["trace[443410591] 'agreement among raft nodes before linearized reading' (duration: 147.679519ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:01:25.939419Z","caller":"traceutil/trace.go:171","msg":"trace[1327823034] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15941; }","duration":"159.148481ms","start":"2026-08-07T12:01:25.780258Z","end":"2026-08-07T12:01:25.939406Z","steps":["trace[1327823034] 'agreement among raft nodes before linearized reading' (duration: 159.06814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:01:25.939519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.24738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-08-07T12:01:25.939555Z","caller":"traceutil/trace.go:171","msg":"trace[1027269866] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15941; }","duration":"193.304432ms","start":"2026-08-07T12:01:25.746242Z","end":"2026-08-07T12:01:25.939546Z","steps":["trace[1027269866] 'agreement among raft nodes before linearized reading' (duration: 193.21627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:01:25.939558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.558723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T12:01:25.939658Z","caller":"traceutil/trace.go:171","msg":"trace[323166301] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:15941; }","duration":"121.707715ms","start":"2026-08-07T12:01:25.817926Z","end":"2026-08-07T12:01:25.939634Z","steps":["trace[323166301] 'agreement among raft nodes before linearized reading' (duration: 121.523612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:01:25.939306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.915089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-08-07T12:01:25.939995Z","caller":"traceutil/trace.go:171","msg":"trace[454776144] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15941; }","duration":"140.636192ms","start":"2026-08-07T12:01:25.799326Z","end":"2026-08-07T12:01:25.939962Z","steps":["trace[454776144] 'agreement among raft nodes before linearized reading' (duration: 139.707213ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:04:35.920645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14649} {"level":"info","ts":"2026-08-07T12:04:35.997075Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14649,"took":"73.301188ms","hash":1411559807,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":33615872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-07T12:04:35.997145Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1411559807,"revision":14649,"compact-revision":12702} {"level":"warn","ts":"2026-08-07T12:07:09.449795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.684695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-07T12:07:09.449872Z","caller":"traceutil/trace.go:171","msg":"trace[387905375] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:18523; }","duration":"106.796627ms","start":"2026-08-07T12:07:09.343059Z","end":"2026-08-07T12:07:09.449856Z","steps":["trace[387905375] 'count revisions from in-memory index tree' (duration: 106.627174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:07:09.44994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.741728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-wk4t5\" ","response":"range_response_count:1 size:20740"} {"level":"info","ts":"2026-08-07T12:07:09.450018Z","caller":"traceutil/trace.go:171","msg":"trace[754301895] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-wk4t5; range_end:; response_count:1; response_revision:18523; }","duration":"128.892662ms","start":"2026-08-07T12:07:09.321107Z","end":"2026-08-07T12:07:09.45Z","steps":["trace[754301895] 'range keys from in-memory index tree' (duration: 128.544595ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:07:09.960834Z","caller":"traceutil/trace.go:171","msg":"trace[597869179] linearizableReadLoop","detail":"{readStateIndex:19159; appliedIndex:19158; }","duration":"166.273828ms","start":"2026-08-07T12:07:09.794504Z","end":"2026-08-07T12:07:09.960778Z","steps":["trace[597869179] 'read index received' (duration: 163.161959ms)","trace[597869179] 'applied index is now lower than readState.Index' (duration: 3.110179ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T12:07:09.960978Z","caller":"traceutil/trace.go:171","msg":"trace[1645342106] transaction","detail":"{read_only:false; response_revision:18525; number_of_response:1; }","duration":"308.25449ms","start":"2026-08-07T12:07:09.652692Z","end":"2026-08-07T12:07:09.960946Z","steps":["trace[1645342106] 'process raft request' (duration: 304.946917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:07:09.961136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T12:07:09.652663Z","time spent":"308.393882ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T12:07:09.96117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.649706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-n7p25\" ","response":"range_response_count:1 size:22093"} {"level":"info","ts":"2026-08-07T12:07:09.961216Z","caller":"traceutil/trace.go:171","msg":"trace[1318351624] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-n7p25; range_end:; response_count:1; response_revision:18525; }","duration":"166.775348ms","start":"2026-08-07T12:07:09.794426Z","end":"2026-08-07T12:07:09.961202Z","steps":["trace[1318351624] 'agreement among raft nodes before linearized reading' (duration: 166.517923ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:07:17.984545Z","caller":"traceutil/trace.go:171","msg":"trace[1883870461] linearizableReadLoop","detail":"{readStateIndex:19292; appliedIndex:19290; }","duration":"123.360687ms","start":"2026-08-07T12:07:17.861143Z","end":"2026-08-07T12:07:17.984503Z","steps":["trace[1883870461] 'read index received' (duration: 4.561819ms)","trace[1883870461] 'applied index is now lower than readState.Index' (duration: 118.797378ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T12:07:17.984762Z","caller":"traceutil/trace.go:171","msg":"trace[327583812] transaction","detail":"{read_only:false; response_revision:18655; number_of_response:1; }","duration":"130.41476ms","start":"2026-08-07T12:07:17.854317Z","end":"2026-08-07T12:07:17.984731Z","steps":["trace[327583812] 'process raft request' (duration: 95.099892ms)","trace[327583812] 'compare' (duration: 34.881039ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T12:07:17.985008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.832755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-08-07T12:07:17.985104Z","caller":"traceutil/trace.go:171","msg":"trace[1216330334] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:18655; }","duration":"123.969597ms","start":"2026-08-07T12:07:17.861108Z","end":"2026-08-07T12:07:17.985078Z","steps":["trace[1216330334] 'agreement among raft nodes before linearized reading' (duration: 123.631631ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:07:17.985131Z","caller":"traceutil/trace.go:171","msg":"trace[1004799873] transaction","detail":"{read_only:false; response_revision:18656; number_of_response:1; }","duration":"100.216152ms","start":"2026-08-07T12:07:17.884898Z","end":"2026-08-07T12:07:17.985114Z","steps":["trace[1004799873] 'process raft request' (duration: 99.968348ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:08:22.484551Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6e120e57916b9bd4","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-07T12:08:22.489851Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-07T12:08:22.490037Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-07T12:09:35.928217Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17448} {"level":"info","ts":"2026-08-07T12:09:36.034314Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17448,"took":"100.735637ms","hash":2364877853,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":34840576,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-07T12:09:36.034393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2364877853,"revision":17448,"compact-revision":14649} {"level":"info","ts":"2026-08-07T12:13:16.579126Z","caller":"traceutil/trace.go:171","msg":"trace[1252186742] transaction","detail":"{read_only:false; response_revision:21308; number_of_response:1; }","duration":"100.495372ms","start":"2026-08-07T12:13:16.478606Z","end":"2026-08-07T12:13:16.579101Z","steps":["trace[1252186742] 'process raft request' (duration: 100.334189ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:13:16.752871Z","caller":"traceutil/trace.go:171","msg":"trace[2136652137] linearizableReadLoop","detail":"{readStateIndex:22037; appliedIndex:22036; }","duration":"153.112563ms","start":"2026-08-07T12:13:16.599729Z","end":"2026-08-07T12:13:16.752841Z","steps":["trace[2136652137] 'read index received' (duration: 140.156844ms)","trace[2136652137] 'applied index is now lower than readState.Index' (duration: 12.954679ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T12:13:16.753Z","caller":"traceutil/trace.go:171","msg":"trace[1687223734] transaction","detail":"{read_only:false; response_revision:21310; number_of_response:1; }","duration":"173.002555ms","start":"2026-08-07T12:13:16.579981Z","end":"2026-08-07T12:13:16.752984Z","steps":["trace[1687223734] 'process raft request' (duration: 159.940025ms)","trace[1687223734] 'compare' (duration: 12.609552ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T12:13:16.753148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.507461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-07T12:13:16.753269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.100747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-07T12:13:16.753326Z","caller":"traceutil/trace.go:171","msg":"trace[1933624080] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21310; }","duration":"160.184729ms","start":"2026-08-07T12:13:16.593128Z","end":"2026-08-07T12:13:16.753313Z","steps":["trace[1933624080] 'agreement among raft nodes before linearized reading' (duration: 160.004855ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:13:16.753388Z","caller":"traceutil/trace.go:171","msg":"trace[1464120302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21310; }","duration":"152.680415ms","start":"2026-08-07T12:13:16.600539Z","end":"2026-08-07T12:13:16.75322Z","steps":["trace[1464120302] 'agreement among raft nodes before linearized reading' (duration: 152.484601ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:13:16.967829Z","caller":"traceutil/trace.go:171","msg":"trace[1815237486] transaction","detail":"{read_only:false; response_revision:21311; number_of_response:1; }","duration":"100.652225ms","start":"2026-08-07T12:13:16.867152Z","end":"2026-08-07T12:13:16.967804Z","steps":["trace[1815237486] 'process raft request' (duration: 34.693698ms)","trace[1815237486] 'compare' (duration: 65.579239ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T12:13:17.724006Z","caller":"traceutil/trace.go:171","msg":"trace[917472483] transaction","detail":"{read_only:false; response_revision:21313; number_of_response:1; }","duration":"276.631568ms","start":"2026-08-07T12:13:17.447335Z","end":"2026-08-07T12:13:17.723966Z","steps":["trace[917472483] 'process raft request' (duration: 276.381253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:13:18.00138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.444314ms","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-07T12:13:18.001542Z","caller":"traceutil/trace.go:171","msg":"trace[279759601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21313; }","duration":"121.606188ms","start":"2026-08-07T12:13:17.879847Z","end":"2026-08-07T12:13:18.001454Z","steps":["trace[279759601] 'range keys from in-memory index tree' (duration: 121.239731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T12:13:18.001698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.025947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-07T12:13:18.001807Z","caller":"traceutil/trace.go:171","msg":"trace[1820724133] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:21313; }","duration":"210.230391ms","start":"2026-08-07T12:13:17.791554Z","end":"2026-08-07T12:13:18.001785Z","steps":["trace[1820724133] 'count revisions from in-memory index tree' (duration: 209.754092ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:13:18.00325Z","caller":"traceutil/trace.go:171","msg":"trace[2117337278] transaction","detail":"{read_only:false; response_revision:21314; number_of_response:1; }","duration":"121.603888ms","start":"2026-08-07T12:13:17.881613Z","end":"2026-08-07T12:13:18.003217Z","steps":["trace[2117337278] 'process raft request' (duration: 121.435155ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:13:18.17286Z","caller":"traceutil/trace.go:171","msg":"trace[972753614] transaction","detail":"{read_only:false; response_revision:21316; number_of_response:1; }","duration":"153.284387ms","start":"2026-08-07T12:13:18.019545Z","end":"2026-08-07T12:13:18.17283Z","steps":["trace[972753614] 'process raft request' (duration: 140.602243ms)","trace[972753614] 'compare' (duration: 12.423979ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T12:13:18.173018Z","caller":"traceutil/trace.go:171","msg":"trace[1329173957] transaction","detail":"{read_only:false; response_revision:21317; number_of_response:1; }","duration":"152.355808ms","start":"2026-08-07T12:13:18.020617Z","end":"2026-08-07T12:13:18.172973Z","steps":["trace[1329173957] 'process raft request' (duration: 152.162125ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:13:18.507419Z","caller":"traceutil/trace.go:171","msg":"trace[815315594] transaction","detail":"{read_only:false; response_revision:21318; number_of_response:1; }","duration":"197.16214ms","start":"2026-08-07T12:13:18.310229Z","end":"2026-08-07T12:13:18.507391Z","steps":["trace[815315594] 'process raft request' (duration: 197.009407ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T12:14:35.934919Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19820} {"level":"info","ts":"2026-08-07T12:14:36.052996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19820,"took":"112.586623ms","hash":961724207,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":30154752,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-07T12:14:36.053083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":961724207,"revision":19820,"compact-revision":17448} {"level":"info","ts":"2026-08-07T12:19:35.949577Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22007} {"level":"info","ts":"2026-08-07T12:19:36.061655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22007,"took":"105.896714ms","hash":2598988084,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":44261376,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-07T12:19:36.061731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2598988084,"revision":22007,"compact-revision":19820} {"level":"info","ts":"2026-08-07T12:22:43.138197Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006af5.wal"} {"level":"info","ts":"2026-08-07T12:24:35.955648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25102} {"level":"info","ts":"2026-08-07T12:24:36.094607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25102,"took":"135.334477ms","hash":1138494823,"current-db-size-bytes":59424768,"current-db-size":"59 MB","current-db-size-in-use-bytes":30478336,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-07T12:24:36.094667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1138494823,"revision":25102,"compact-revision":22007}