{"level":"warn","ts":"2026-08-15T10:02:47.886794Z","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-15T10:02:47.886947Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-15T10:02:47.887118Z","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-15T10:02:47.887158Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-08-15T10:02:47.887201Z","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-15T10:02:47.888528Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-08-15T10:02:47.888707Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-15T10:02:47.891449Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.371414ms"} {"level":"info","ts":"2026-08-15T10:02:47.897095Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-08-15T10:02:47.897184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-08-15T10:02:47.897217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-08-15T10:02:47.897234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-15T10:02:47.897244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-08-15T10:02:47.897288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-08-15T10:02:47.900876Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-15T10:02:47.902606Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-15T10:02:47.904367Z","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-15T10:02:47.908686Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-15T10:02:47.910307Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-15T10:02:47.910543Z","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-15T10:02:47.910593Z","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-15T10:02:47.910605Z","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-15T10:02:47.91092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-08-15T10:02:47.91216Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-08-15T10:02:47.917231Z","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-15T10:02:47.917306Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-08-15T10:02:47.917327Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-08-15T10:02:47.917461Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-15T10:02:47.917505Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-15T10:02:48.597531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-08-15T10:02:48.597595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-15T10:02:48.597637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-08-15T10:02:48.597657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-08-15T10:02:48.597668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-08-15T10:02:48.597684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-08-15T10:02:48.597706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-08-15T10:02:48.599025Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T10:02:48.600026Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T10:02:48.600027Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-08-15T10:02:48.600315Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T10:02:48.600638Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-15T10:02:48.600656Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-15T10:02:48.600786Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T10:02:48.601027Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T10:02:48.601069Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T10:02:48.603208Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-08-15T10:02:48.603468Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-15T10:02:51.29127Z","caller":"traceutil/trace.go:171","msg":"trace[1703446503] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"106.605716ms","start":"2026-08-15T10:02:51.184638Z","end":"2026-08-15T10:02:51.291243Z","steps":["trace[1703446503] 'process raft request' (duration: 94.888416ms)","trace[1703446503] 'compare' (duration: 11.623798ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:03:31.869293Z","caller":"traceutil/trace.go:171","msg":"trace[1684366728] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"406.130951ms","start":"2026-08-15T10:03:31.463135Z","end":"2026-08-15T10:03:31.869266Z","steps":["trace[1684366728] 'read index received' (duration: 405.824925ms)","trace[1684366728] 'applied index is now lower than readState.Index' (duration: 304.576µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:03:31.869365Z","caller":"traceutil/trace.go:171","msg":"trace[2040972741] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"413.526192ms","start":"2026-08-15T10:03:31.45579Z","end":"2026-08-15T10:03:31.869316Z","steps":["trace[2040972741] 'process raft request' (duration: 413.187687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:03:31.869705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.586964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T10:03:31.869819Z","caller":"traceutil/trace.go:171","msg":"trace[1392008210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"201.868959ms","start":"2026-08-15T10:03:31.667925Z","end":"2026-08-15T10:03:31.869794Z","steps":["trace[1392008210] 'agreement among raft nodes before linearized reading' (duration: 201.529403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:03:31.869676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.348214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vwpnh\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-15T10:03:31.870106Z","caller":"traceutil/trace.go:171","msg":"trace[2073459305] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vwpnh; range_end:; response_count:1; response_revision:625; }","duration":"406.947066ms","start":"2026-08-15T10:03:31.463108Z","end":"2026-08-15T10:03:31.870055Z","steps":["trace[2073459305] 'agreement among raft nodes before linearized reading' (duration: 406.244742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:03:31.870276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:03:31.463088Z","time spent":"407.054677ms","remote":"127.0.0.1:47738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-vwpnh\" "} {"level":"warn","ts":"2026-08-15T10:03:31.871944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.083898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-15T10:03:31.872006Z","caller":"traceutil/trace.go:171","msg":"trace[723873155] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:625; }","duration":"165.18714ms","start":"2026-08-15T10:03:31.706806Z","end":"2026-08-15T10:03:31.871993Z","steps":["trace[723873155] 'agreement among raft nodes before linearized reading' (duration: 164.855584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:03:31.870276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:03:31.455741Z","time spent":"413.767687ms","remote":"127.0.0.1:47906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T10:05:07.204638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.999702ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-15T10:05:12.666099Z","caller":"traceutil/trace.go:171","msg":"trace[286053269] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"155.439779ms","start":"2026-08-15T10:05:12.510636Z","end":"2026-08-15T10:05:12.666076Z","steps":["trace[286053269] 'process raft request' (duration: 155.292386ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:05:12.66846Z","caller":"traceutil/trace.go:171","msg":"trace[1155949436] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"157.360521ms","start":"2026-08-15T10:05:12.511075Z","end":"2026-08-15T10:05:12.668435Z","steps":["trace[1155949436] 'process raft request' (duration: 157.244448ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:05:44.405714Z","caller":"traceutil/trace.go:171","msg":"trace[1820082640] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2227; }","duration":"149.980938ms","start":"2026-08-15T10:05:44.255697Z","end":"2026-08-15T10:05:44.405678Z","steps":["trace[1820082640] 'read index received' (duration: 149.52135ms)","trace[1820082640] 'applied index is now lower than readState.Index' (duration: 458.108µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:05:44.405762Z","caller":"traceutil/trace.go:171","msg":"trace[1233396111] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"152.375637ms","start":"2026-08-15T10:05:44.253351Z","end":"2026-08-15T10:05:44.405727Z","steps":["trace[1233396111] 'process raft request' (duration: 151.996901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:05:44.405953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.162471ms","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-15T10:05:44.406068Z","caller":"traceutil/trace.go:171","msg":"trace[1866755404] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2148; }","duration":"150.389205ms","start":"2026-08-15T10:05:44.255654Z","end":"2026-08-15T10:05:44.406044Z","steps":["trace[1866755404] 'agreement among raft nodes before linearized reading' (duration: 150.142931ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:05:51.028767Z","caller":"traceutil/trace.go:171","msg":"trace[972224147] linearizableReadLoop","detail":"{readStateIndex:2260; appliedIndex:2259; }","duration":"126.513387ms","start":"2026-08-15T10:05:50.902223Z","end":"2026-08-15T10:05:51.028737Z","steps":["trace[972224147] 'read index received' (duration: 126.249692ms)","trace[972224147] 'applied index is now lower than readState.Index' (duration: 262.475µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T10:05:51.029011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.773601ms","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-15T10:05:51.028992Z","caller":"traceutil/trace.go:171","msg":"trace[1476066701] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"136.667636ms","start":"2026-08-15T10:05:50.892293Z","end":"2026-08-15T10:05:51.028961Z","steps":["trace[1476066701] 'process raft request' (duration: 136.28417ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:05:51.029077Z","caller":"traceutil/trace.go:171","msg":"trace[424218706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2179; }","duration":"126.890103ms","start":"2026-08-15T10:05:50.902172Z","end":"2026-08-15T10:05:51.029062Z","steps":["trace[424218706] 'agreement among raft nodes before linearized reading' (duration: 126.69794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:05:51.27878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.531871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T10:05:51.278952Z","caller":"traceutil/trace.go:171","msg":"trace[834639996] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"245.121241ms","start":"2026-08-15T10:05:51.033802Z","end":"2026-08-15T10:05:51.278923Z","steps":["trace[834639996] 'process raft request' (duration: 113.113863ms)","trace[834639996] 'compare' (duration: 131.336297ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:05:51.283441Z","caller":"traceutil/trace.go:171","msg":"trace[1843475767] linearizableReadLoop","detail":"{readStateIndex:2262; appliedIndex:2261; }","duration":"103.689466ms","start":"2026-08-15T10:05:51.179715Z","end":"2026-08-15T10:05:51.283405Z","steps":["trace[1843475767] 'read index received' (duration: 103.412842ms)","trace[1843475767] 'applied index is now lower than readState.Index' (duration: 275.224µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:05:51.283533Z","caller":"traceutil/trace.go:171","msg":"trace[724250901] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"152.205174ms","start":"2026-08-15T10:05:51.131305Z","end":"2026-08-15T10:05:51.28351Z","steps":["trace[724250901] 'process raft request' (duration: 151.94225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:05:51.283769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.033693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-15T10:05:51.283816Z","caller":"traceutil/trace.go:171","msg":"trace[1808238034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2181; }","duration":"104.103354ms","start":"2026-08-15T10:05:51.179701Z","end":"2026-08-15T10:05:51.283804Z","steps":["trace[1808238034] 'agreement among raft nodes before linearized reading' (duration: 103.88493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:05:51.284009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.343894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-15T10:05:51.284148Z","caller":"traceutil/trace.go:171","msg":"trace[1513929337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2181; }","duration":"102.523896ms","start":"2026-08-15T10:05:51.181605Z","end":"2026-08-15T10:05:51.284129Z","steps":["trace[1513929337] 'agreement among raft nodes before linearized reading' (duration: 102.082869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:07:17.424367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.445373ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-15T10:07:17.461317Z","caller":"traceutil/trace.go:171","msg":"trace[106025424] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"120.276408ms","start":"2026-08-15T10:07:17.34098Z","end":"2026-08-15T10:07:17.461256Z","steps":["trace[106025424] 'process raft request' (duration: 119.935862ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:09:48.341275Z","caller":"traceutil/trace.go:171","msg":"trace[1226178838] linearizableReadLoop","detail":"{readStateIndex:3981; appliedIndex:3980; }","duration":"120.720029ms","start":"2026-08-15T10:09:48.220534Z","end":"2026-08-15T10:09:48.341254Z","steps":["trace[1226178838] 'read index received' (duration: 120.566206ms)","trace[1226178838] 'applied index is now lower than readState.Index' (duration: 152.823µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:09:48.34138Z","caller":"traceutil/trace.go:171","msg":"trace[1871135596] transaction","detail":"{read_only:false; response_revision:3835; number_of_response:1; }","duration":"126.018012ms","start":"2026-08-15T10:09:48.215337Z","end":"2026-08-15T10:09:48.341355Z","steps":["trace[1871135596] 'process raft request' (duration: 125.768287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:09:48.341518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.976593ms","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-15T10:09:48.341544Z","caller":"traceutil/trace.go:171","msg":"trace[1149577464] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3835; }","duration":"121.022064ms","start":"2026-08-15T10:09:48.220515Z","end":"2026-08-15T10:09:48.341537Z","steps":["trace[1149577464] 'agreement among raft nodes before linearized reading' (duration: 120.830801ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:11:14.157733Z","caller":"traceutil/trace.go:171","msg":"trace[1282998573] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"102.027186ms","start":"2026-08-15T10:11:14.055652Z","end":"2026-08-15T10:11:14.157679Z","steps":["trace[1282998573] 'process raft request' (duration: 100.719572ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:11:19.917069Z","caller":"traceutil/trace.go:171","msg":"trace[1029007024] linearizableReadLoop","detail":"{readStateIndex:5229; appliedIndex:5228; }","duration":"164.965616ms","start":"2026-08-15T10:11:19.752075Z","end":"2026-08-15T10:11:19.917041Z","steps":["trace[1029007024] 'read index received' (duration: 164.432416ms)","trace[1029007024] 'applied index is now lower than readState.Index' (duration: 532.02µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:11:19.917121Z","caller":"traceutil/trace.go:171","msg":"trace[295117742] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"193.321927ms","start":"2026-08-15T10:11:19.723762Z","end":"2026-08-15T10:11:19.917084Z","steps":["trace[295117742] 'process raft request' (duration: 192.91417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:11:19.917238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.14218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T10:11:19.917315Z","caller":"traceutil/trace.go:171","msg":"trace[986688015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5041; }","duration":"165.283253ms","start":"2026-08-15T10:11:19.752016Z","end":"2026-08-15T10:11:19.917299Z","steps":["trace[986688015] 'agreement among raft nodes before linearized reading' (duration: 165.135441ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:11:20.265507Z","caller":"traceutil/trace.go:171","msg":"trace[804076026] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"134.24182ms","start":"2026-08-15T10:11:20.131238Z","end":"2026-08-15T10:11:20.26548Z","steps":["trace[804076026] 'process raft request' (duration: 133.977475ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:11:33.924208Z","caller":"traceutil/trace.go:171","msg":"trace[1930253464] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"120.381226ms","start":"2026-08-15T10:11:33.803794Z","end":"2026-08-15T10:11:33.924176Z","steps":["trace[1930253464] 'process raft request' (duration: 120.0532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:11:34.148311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.088228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-15T10:11:34.148522Z","caller":"traceutil/trace.go:171","msg":"trace[1589695704] linearizableReadLoop","detail":"{readStateIndex:5523; appliedIndex:5521; }","duration":"143.531961ms","start":"2026-08-15T10:11:34.00497Z","end":"2026-08-15T10:11:34.148502Z","steps":["trace[1589695704] 'read index received' (duration: 16.328904ms)","trace[1589695704] 'applied index is now lower than readState.Index' (duration: 127.201497ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T10:11:34.148672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.690395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T10:11:34.148711Z","caller":"traceutil/trace.go:171","msg":"trace[1326728992] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:5326; }","duration":"143.777576ms","start":"2026-08-15T10:11:34.004922Z","end":"2026-08-15T10:11:34.148699Z","steps":["trace[1326728992] 'agreement among raft nodes before linearized reading' (duration: 143.642383ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:11:34.149024Z","caller":"traceutil/trace.go:171","msg":"trace[28852536] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"206.847789ms","start":"2026-08-15T10:11:33.942162Z","end":"2026-08-15T10:11:34.14901Z","steps":["trace[28852536] 'process raft request' (duration: 29.971656ms)","trace[28852536] 'compare' (duration: 175.894004ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:11:34.149057Z","caller":"traceutil/trace.go:171","msg":"trace[1018183592] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"206.259628ms","start":"2026-08-15T10:11:33.942766Z","end":"2026-08-15T10:11:34.149025Z","steps":["trace[1018183592] 'process raft request' (duration: 205.675377ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:11:42.59741Z","caller":"traceutil/trace.go:171","msg":"trace[1621623934] linearizableReadLoop","detail":"{readStateIndex:5736; appliedIndex:5734; }","duration":"119.013939ms","start":"2026-08-15T10:11:42.47837Z","end":"2026-08-15T10:11:42.597384Z","steps":["trace[1621623934] 'read index received' (duration: 26.634092ms)","trace[1621623934] 'applied index is now lower than readState.Index' (duration: 92.378517ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:11:42.597596Z","caller":"traceutil/trace.go:171","msg":"trace[159895776] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"166.061104ms","start":"2026-08-15T10:11:42.431511Z","end":"2026-08-15T10:11:42.597572Z","steps":["trace[159895776] 'process raft request' (duration: 158.579601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:11:42.597775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.243594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-15T10:11:42.597822Z","caller":"traceutil/trace.go:171","msg":"trace[1455997641] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5533; }","duration":"119.505209ms","start":"2026-08-15T10:11:42.478302Z","end":"2026-08-15T10:11:42.597807Z","steps":["trace[1455997641] 'agreement among raft nodes before linearized reading' (duration: 119.186363ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:12:48.630945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3013} {"level":"info","ts":"2026-08-15T10:12:48.720044Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3013,"took":"87.629476ms","hash":2802635106,"current-db-size-bytes":58634240,"current-db-size":"59 MB","current-db-size-in-use-bytes":31748096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-15T10:12:48.720129Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2802635106,"revision":3013,"compact-revision":-1} {"level":"info","ts":"2026-08-15T10:17:48.636786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6084} {"level":"info","ts":"2026-08-15T10:17:48.735461Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6084,"took":"95.909024ms","hash":3618826264,"current-db-size-bytes":58634240,"current-db-size":"59 MB","current-db-size-in-use-bytes":22007808,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-15T10:17:48.73555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3618826264,"revision":6084,"compact-revision":3013} {"level":"info","ts":"2026-08-15T10:18:03.86929Z","caller":"traceutil/trace.go:171","msg":"trace[1539444650] linearizableReadLoop","detail":"{readStateIndex:8681; appliedIndex:8680; }","duration":"162.524865ms","start":"2026-08-15T10:18:03.706742Z","end":"2026-08-15T10:18:03.869267Z","steps":["trace[1539444650] 'read index received' (duration: 162.421353ms)","trace[1539444650] 'applied index is now lower than readState.Index' (duration: 102.122µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:18:03.869379Z","caller":"traceutil/trace.go:171","msg":"trace[1967526914] transaction","detail":"{read_only:false; response_revision:8388; number_of_response:1; }","duration":"195.155965ms","start":"2026-08-15T10:18:03.674171Z","end":"2026-08-15T10:18:03.869327Z","steps":["trace[1967526914] 'process raft request' (duration: 194.93596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:18:03.869496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.69044ms","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-15T10:18:03.869539Z","caller":"traceutil/trace.go:171","msg":"trace[30723298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8388; }","duration":"162.820072ms","start":"2026-08-15T10:18:03.706709Z","end":"2026-08-15T10:18:03.869529Z","steps":["trace[30723298] 'agreement among raft nodes before linearized reading' (duration: 162.641758ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:04.009024Z","caller":"traceutil/trace.go:171","msg":"trace[666375160] transaction","detail":"{read_only:false; response_revision:8389; number_of_response:1; }","duration":"135.035259ms","start":"2026-08-15T10:18:03.873963Z","end":"2026-08-15T10:18:04.008998Z","steps":["trace[666375160] 'process raft request' (duration: 133.830817ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:15.462571Z","caller":"traceutil/trace.go:171","msg":"trace[1267682073] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"259.245113ms","start":"2026-08-15T10:18:15.203287Z","end":"2026-08-15T10:18:15.462532Z","steps":["trace[1267682073] 'process raft request' (duration: 259.023419ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:15.469385Z","caller":"traceutil/trace.go:171","msg":"trace[1649648128] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"264.340794ms","start":"2026-08-15T10:18:15.205023Z","end":"2026-08-15T10:18:15.469364Z","steps":["trace[1649648128] 'process raft request' (duration: 264.174851ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:15.469631Z","caller":"traceutil/trace.go:171","msg":"trace[1699651254] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"264.260733ms","start":"2026-08-15T10:18:15.20533Z","end":"2026-08-15T10:18:15.469591Z","steps":["trace[1699651254] 'process raft request' (duration: 263.980878ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:16.285526Z","caller":"traceutil/trace.go:171","msg":"trace[1982072348] transaction","detail":"{read_only:false; response_revision:8450; number_of_response:1; }","duration":"198.15818ms","start":"2026-08-15T10:18:16.087323Z","end":"2026-08-15T10:18:16.285482Z","steps":["trace[1982072348] 'process raft request' (duration: 197.781503ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:16.474287Z","caller":"traceutil/trace.go:171","msg":"trace[342802827] transaction","detail":"{read_only:false; response_revision:8452; number_of_response:1; }","duration":"236.390579ms","start":"2026-08-15T10:18:16.23787Z","end":"2026-08-15T10:18:16.47426Z","steps":["trace[342802827] 'process raft request' (duration: 236.326318ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:16.474354Z","caller":"traceutil/trace.go:171","msg":"trace[2133170344] transaction","detail":"{read_only:false; response_revision:8451; number_of_response:1; }","duration":"322.511815ms","start":"2026-08-15T10:18:16.151798Z","end":"2026-08-15T10:18:16.47431Z","steps":["trace[2133170344] 'process raft request' (duration: 322.22949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:18:16.474547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:18:16.151771Z","time spent":"322.667888ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T10:18:19.686849Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002245.wal"} {"level":"info","ts":"2026-08-15T10:18:44.867298Z","caller":"traceutil/trace.go:171","msg":"trace[1344451771] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"288.020012ms","start":"2026-08-15T10:18:44.579256Z","end":"2026-08-15T10:18:44.867276Z","steps":["trace[1344451771] 'process raft request' (duration: 287.875759ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:45.044317Z","caller":"traceutil/trace.go:171","msg":"trace[1483771753] linearizableReadLoop","detail":"{readStateIndex:8962; appliedIndex:8960; }","duration":"376.421449ms","start":"2026-08-15T10:18:44.667855Z","end":"2026-08-15T10:18:45.044277Z","steps":["trace[1483771753] 'read index received' (duration: 199.275019ms)","trace[1483771753] 'applied index is now lower than readState.Index' (duration: 177.14481ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T10:18:45.04453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.641604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T10:18:45.044578Z","caller":"traceutil/trace.go:171","msg":"trace[1534000069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8660; }","duration":"376.758405ms","start":"2026-08-15T10:18:44.667805Z","end":"2026-08-15T10:18:45.044563Z","steps":["trace[1534000069] 'agreement among raft nodes before linearized reading' (duration: 376.615893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:18:45.044627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:18:44.667783Z","time spent":"376.831437ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-15T10:18:45.044663Z","caller":"traceutil/trace.go:171","msg":"trace[321653058] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"462.450402ms","start":"2026-08-15T10:18:44.582181Z","end":"2026-08-15T10:18:45.044632Z","steps":["trace[321653058] 'process raft request' (duration: 429.013348ms)","trace[321653058] 'compare' (duration: 32.400835ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T10:18:45.044846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:18:44.582152Z","time spent":"462.592755ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T10:18:45.044925Z","caller":"traceutil/trace.go:171","msg":"trace[2141514443] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"266.635256ms","start":"2026-08-15T10:18:44.778272Z","end":"2026-08-15T10:18:45.044907Z","steps":["trace[2141514443] 'process raft request' (duration: 265.867363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:18:49.071196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.093747ms","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-15T10:18:49.071255Z","caller":"traceutil/trace.go:171","msg":"trace[998489798] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8677; }","duration":"250.190379ms","start":"2026-08-15T10:18:48.821051Z","end":"2026-08-15T10:18:49.071242Z","steps":["trace[998489798] 'count revisions from in-memory index tree' (duration: 249.987125ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:49.071785Z","caller":"traceutil/trace.go:171","msg":"trace[720958842] transaction","detail":"{read_only:false; response_revision:8678; number_of_response:1; }","duration":"189.95192ms","start":"2026-08-15T10:18:48.881825Z","end":"2026-08-15T10:18:49.071777Z","steps":["trace[720958842] 'process raft request' (duration: 119.193552ms)","trace[720958842] 'compare' (duration: 70.408872ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:18:49.272305Z","caller":"traceutil/trace.go:171","msg":"trace[2025332780] linearizableReadLoop","detail":"{readStateIndex:8986; appliedIndex:8983; }","duration":"107.875919ms","start":"2026-08-15T10:18:49.164395Z","end":"2026-08-15T10:18:49.272271Z","steps":["trace[2025332780] 'read index received' (duration: 11.588351ms)","trace[2025332780] 'applied index is now lower than readState.Index' (duration: 96.286048ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:18:49.272381Z","caller":"traceutil/trace.go:171","msg":"trace[897541519] transaction","detail":"{read_only:false; response_revision:8680; number_of_response:1; }","duration":"209.054116ms","start":"2026-08-15T10:18:49.0633Z","end":"2026-08-15T10:18:49.272354Z","steps":["trace[897541519] 'process raft request' (duration: 208.843142ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:49.272375Z","caller":"traceutil/trace.go:171","msg":"trace[876876061] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"212.631101ms","start":"2026-08-15T10:18:49.059709Z","end":"2026-08-15T10:18:49.27234Z","steps":["trace[876876061] 'process raft request' (duration: 116.25935ms)","trace[876876061] 'compare' (duration: 95.970403ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:18:49.27255Z","caller":"traceutil/trace.go:171","msg":"trace[1941842766] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"128.447ms","start":"2026-08-15T10:18:49.144062Z","end":"2026-08-15T10:18:49.272509Z","steps":["trace[1941842766] 'process raft request' (duration: 128.154314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:18:49.27303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.613532ms","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-15T10:18:49.273094Z","caller":"traceutil/trace.go:171","msg":"trace[67994463] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8681; }","duration":"108.736155ms","start":"2026-08-15T10:18:49.164342Z","end":"2026-08-15T10:18:49.273078Z","steps":["trace[67994463] 'agreement among raft nodes before linearized reading' (duration: 108.063043ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:57.11051Z","caller":"traceutil/trace.go:171","msg":"trace[2068131420] transaction","detail":"{read_only:false; response_revision:8761; number_of_response:1; }","duration":"138.384379ms","start":"2026-08-15T10:18:56.972099Z","end":"2026-08-15T10:18:57.110483Z","steps":["trace[2068131420] 'process raft request' (duration: 52.507537ms)","trace[2068131420] 'compare' (duration: 85.732959ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:18:57.136902Z","caller":"traceutil/trace.go:171","msg":"trace[310390447] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8762; }","duration":"163.172707ms","start":"2026-08-15T10:18:56.973707Z","end":"2026-08-15T10:18:57.136879Z","steps":["trace[310390447] 'process raft request' (duration: 163.017734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:18:57.405994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.309085ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-15T10:18:57.406318Z","caller":"traceutil/trace.go:171","msg":"trace[920946072] transaction","detail":"{read_only:false; response_revision:8764; number_of_response:1; }","duration":"104.701912ms","start":"2026-08-15T10:18:57.301598Z","end":"2026-08-15T10:18:57.4063Z","steps":["trace[920946072] 'process raft request' (duration: 104.645651ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:57.406456Z","caller":"traceutil/trace.go:171","msg":"trace[182293581] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"305.865936ms","start":"2026-08-15T10:18:57.100579Z","end":"2026-08-15T10:18:57.406445Z","steps":["trace[182293581] 'process raft request' (duration: 305.52569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:18:57.406558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:18:57.100547Z","time spent":"305.936576ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T10:18:57.406654Z","caller":"traceutil/trace.go:171","msg":"trace[1059265468] linearizableReadLoop","detail":"{readStateIndex:9073; appliedIndex:9070; }","duration":"291.296951ms","start":"2026-08-15T10:18:57.115347Z","end":"2026-08-15T10:18:57.406644Z","steps":["trace[1059265468] 'read index received' (duration: 21.369415ms)","trace[1059265468] 'applied index is now lower than readState.Index' (duration: 269.926506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T10:18:57.406806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.467814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-89vh6\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-08-15T10:18:57.406855Z","caller":"traceutil/trace.go:171","msg":"trace[1705851928] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-89vh6; range_end:; response_count:1; response_revision:8764; }","duration":"291.555376ms","start":"2026-08-15T10:18:57.115291Z","end":"2026-08-15T10:18:57.406846Z","steps":["trace[1705851928] 'agreement among raft nodes before linearized reading' (duration: 291.413463ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:57.418046Z","caller":"traceutil/trace.go:171","msg":"trace[228950645] transaction","detail":"{read_only:false; response_revision:8765; number_of_response:1; }","duration":"115.573817ms","start":"2026-08-15T10:18:57.302461Z","end":"2026-08-15T10:18:57.418035Z","steps":["trace[228950645] 'process raft request' (duration: 115.490235ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:18:57.418285Z","caller":"traceutil/trace.go:171","msg":"trace[1549257754] transaction","detail":"{read_only:false; response_revision:8766; number_of_response:1; }","duration":"115.74537ms","start":"2026-08-15T10:18:57.302507Z","end":"2026-08-15T10:18:57.418252Z","steps":["trace[1549257754] 'process raft request' (duration: 115.497185ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:20:33.801453Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-15T10:20:33.80853Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-15T10:20:33.808628Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-15T10:22:48.646229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8168} {"level":"info","ts":"2026-08-15T10:22:48.711858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8168,"took":"62.939375ms","hash":1138642740,"current-db-size-bytes":58634240,"current-db-size":"59 MB","current-db-size-in-use-bytes":28008448,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-15T10:22:48.711932Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1138642740,"revision":8168,"compact-revision":6084} {"level":"info","ts":"2026-08-15T10:25:56.194508Z","caller":"traceutil/trace.go:171","msg":"trace[379981393] transaction","detail":"{read_only:false; response_revision:11970; number_of_response:1; }","duration":"121.1941ms","start":"2026-08-15T10:25:56.073267Z","end":"2026-08-15T10:25:56.194461Z","steps":["trace[379981393] 'process raft request' (duration: 97.008809ms)","trace[379981393] 'compare' (duration: 23.962477ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:27:48.655965Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10533} {"level":"info","ts":"2026-08-15T10:27:48.74179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10533,"took":"83.371267ms","hash":4020089501,"current-db-size-bytes":58634240,"current-db-size":"59 MB","current-db-size-in-use-bytes":26275840,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-15T10:27:48.741877Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4020089501,"revision":10533,"compact-revision":8168} {"level":"info","ts":"2026-08-15T10:32:06.921897Z","caller":"traceutil/trace.go:171","msg":"trace[479958109] transaction","detail":"{read_only:false; response_revision:14471; number_of_response:1; }","duration":"139.436402ms","start":"2026-08-15T10:32:06.782436Z","end":"2026-08-15T10:32:06.921872Z","steps":["trace[479958109] 'process raft request' (duration: 51.787992ms)","trace[479958109] 'compare' (duration: 87.370295ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:32:48.66353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12722} {"level":"info","ts":"2026-08-15T10:32:48.738547Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12722,"took":"72.370668ms","hash":2406244853,"current-db-size-bytes":58634240,"current-db-size":"59 MB","current-db-size-in-use-bytes":27492352,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-15T10:32:48.738623Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2406244853,"revision":12722,"compact-revision":10533} {"level":"info","ts":"2026-08-15T10:34:32.840232Z","caller":"traceutil/trace.go:171","msg":"trace[1033985592] transaction","detail":"{read_only:false; response_revision:15794; number_of_response:1; }","duration":"325.191475ms","start":"2026-08-15T10:34:32.515017Z","end":"2026-08-15T10:34:32.840208Z","steps":["trace[1033985592] 'process raft request' (duration: 325.041172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:34:32.840397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:34:32.51498Z","time spent":"325.327637ms","remote":"127.0.0.1:47736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T10:34:32.841175Z","caller":"traceutil/trace.go:171","msg":"trace[714142557] linearizableReadLoop","detail":"{readStateIndex:16334; appliedIndex:16334; }","duration":"258.301648ms","start":"2026-08-15T10:34:32.582856Z","end":"2026-08-15T10:34:32.841157Z","steps":["trace[714142557] 'read index received' (duration: 258.266637ms)","trace[714142557] 'applied index is now lower than readState.Index' (duration: 33.101µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T10:34:32.841361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.511892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-wc6gv\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-08-15T10:34:32.841402Z","caller":"traceutil/trace.go:171","msg":"trace[180186324] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-wc6gv; range_end:; response_count:1; response_revision:15794; }","duration":"258.605483ms","start":"2026-08-15T10:34:32.582785Z","end":"2026-08-15T10:34:32.84139Z","steps":["trace[180186324] 'agreement among raft nodes before linearized reading' (duration: 258.44976ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:34:32.877689Z","caller":"traceutil/trace.go:171","msg":"trace[416451259] transaction","detail":"{read_only:false; response_revision:15795; number_of_response:1; }","duration":"169.101396ms","start":"2026-08-15T10:34:32.708546Z","end":"2026-08-15T10:34:32.877648Z","steps":["trace[416451259] 'process raft request' (duration: 168.539266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:34:32.879153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.71263ms","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-15T10:34:32.879515Z","caller":"traceutil/trace.go:171","msg":"trace[2137823444] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15795; }","duration":"256.081467ms","start":"2026-08-15T10:34:32.623384Z","end":"2026-08-15T10:34:32.879466Z","steps":["trace[2137823444] 'agreement among raft nodes before linearized reading' (duration: 255.66054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:34:32.879176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.368407ms","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-15T10:34:32.87974Z","caller":"traceutil/trace.go:171","msg":"trace[466285819] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15795; }","duration":"262.937238ms","start":"2026-08-15T10:34:32.616761Z","end":"2026-08-15T10:34:32.879698Z","steps":["trace[466285819] 'agreement among raft nodes before linearized reading' (duration: 262.335707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:34:32.879356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.49961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-wc6gv\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-08-15T10:34:32.87989Z","caller":"traceutil/trace.go:171","msg":"trace[1771121014] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-wc6gv; range_end:; response_count:1; response_revision:15795; }","duration":"240.05497ms","start":"2026-08-15T10:34:32.639824Z","end":"2026-08-15T10:34:32.879879Z","steps":["trace[1771121014] 'agreement among raft nodes before linearized reading' (duration: 239.420728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:34:32.87937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.10771ms","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-15T10:34:32.879998Z","caller":"traceutil/trace.go:171","msg":"trace[300808435] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15795; }","duration":"263.790453ms","start":"2026-08-15T10:34:32.616198Z","end":"2026-08-15T10:34:32.879988Z","steps":["trace[300808435] 'agreement among raft nodes before linearized reading' (duration: 263.052179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:34:33.041866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.178718ms","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-15T10:34:33.041967Z","caller":"traceutil/trace.go:171","msg":"trace[1929233351] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15795; }","duration":"105.31934ms","start":"2026-08-15T10:34:32.936626Z","end":"2026-08-15T10:34:33.041946Z","steps":["trace[1929233351] 'range keys from in-memory index tree' (duration: 105.019815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:34:33.042318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.192983ms","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-15T10:34:33.042826Z","caller":"traceutil/trace.go:171","msg":"trace[2042282966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15795; }","duration":"109.771904ms","start":"2026-08-15T10:34:32.933036Z","end":"2026-08-15T10:34:33.042808Z","steps":["trace[2042282966] 'range keys from in-memory index tree' (duration: 109.03485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:34:33.042318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.578019ms","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-15T10:34:33.042961Z","caller":"traceutil/trace.go:171","msg":"trace[419328409] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15795; }","duration":"112.255801ms","start":"2026-08-15T10:34:32.930689Z","end":"2026-08-15T10:34:33.042945Z","steps":["trace[419328409] 'range keys from in-memory index tree' (duration: 111.430317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:34:33.042527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.28176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-15T10:34:33.042701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.820895ms","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-15T10:34:33.043157Z","caller":"traceutil/trace.go:171","msg":"trace[1519935050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15795; }","duration":"126.890791ms","start":"2026-08-15T10:34:32.916198Z","end":"2026-08-15T10:34:33.043089Z","steps":["trace[1519935050] 'range keys from in-memory index tree' (duration: 126.043134ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:34:33.043279Z","caller":"traceutil/trace.go:171","msg":"trace[147118515] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15795; }","duration":"110.393046ms","start":"2026-08-15T10:34:32.932819Z","end":"2026-08-15T10:34:33.043212Z","steps":["trace[147118515] 'range keys from in-memory index tree' (duration: 109.768964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:34:33.042748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.860323ms","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-15T10:34:33.043476Z","caller":"traceutil/trace.go:171","msg":"trace[857349996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15795; }","duration":"114.629927ms","start":"2026-08-15T10:34:32.928816Z","end":"2026-08-15T10:34:33.043446Z","steps":["trace[857349996] 'range keys from in-memory index tree' (duration: 113.513916ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:34:33.318529Z","caller":"traceutil/trace.go:171","msg":"trace[1183107146] transaction","detail":"{read_only:false; response_revision:15799; number_of_response:1; }","duration":"102.382173ms","start":"2026-08-15T10:34:33.21612Z","end":"2026-08-15T10:34:33.318502Z","steps":["trace[1183107146] 'process raft request' (duration: 102.20985ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:34:33.320525Z","caller":"traceutil/trace.go:171","msg":"trace[1856113766] transaction","detail":"{read_only:false; response_revision:15800; number_of_response:1; }","duration":"102.507375ms","start":"2026-08-15T10:34:33.217998Z","end":"2026-08-15T10:34:33.320505Z","steps":["trace[1856113766] 'process raft request' (duration: 102.002225ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:34:33.320923Z","caller":"traceutil/trace.go:171","msg":"trace[2085705323] transaction","detail":"{read_only:false; response_revision:15801; number_of_response:1; }","duration":"102.516975ms","start":"2026-08-15T10:34:33.218383Z","end":"2026-08-15T10:34:33.3209Z","steps":["trace[2085705323] 'process raft request' (duration: 101.746071ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:37:48.677554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14713} {"level":"info","ts":"2026-08-15T10:37:48.754624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14713,"took":"73.691967ms","hash":4226181826,"current-db-size-bytes":58634240,"current-db-size":"59 MB","current-db-size-in-use-bytes":33345536,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-15T10:37:48.754681Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4226181826,"revision":14713,"compact-revision":12722} {"level":"info","ts":"2026-08-15T10:37:50.191075Z","caller":"traceutil/trace.go:171","msg":"trace[819866027] transaction","detail":"{read_only:false; response_revision:17343; number_of_response:1; }","duration":"107.133669ms","start":"2026-08-15T10:37:50.083895Z","end":"2026-08-15T10:37:50.191029Z","steps":["trace[819866027] 'process raft request' (duration: 92.62914ms)","trace[819866027] 'compare' (duration: 14.328106ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:40:50.978307Z","caller":"traceutil/trace.go:171","msg":"trace[456675676] transaction","detail":"{read_only:false; response_revision:18528; number_of_response:1; }","duration":"212.565328ms","start":"2026-08-15T10:40:50.765715Z","end":"2026-08-15T10:40:50.978281Z","steps":["trace[456675676] 'process raft request' (duration: 152.995836ms)","trace[456675676] 'compare' (duration: 59.425169ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:40:50.979524Z","caller":"traceutil/trace.go:171","msg":"trace[30946074] transaction","detail":"{read_only:false; response_revision:18529; number_of_response:1; }","duration":"201.760591ms","start":"2026-08-15T10:40:50.777735Z","end":"2026-08-15T10:40:50.979495Z","steps":["trace[30946074] 'process raft request' (duration: 201.473796ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:40:50.979524Z","caller":"traceutil/trace.go:171","msg":"trace[2078492257] transaction","detail":"{read_only:false; response_revision:18530; number_of_response:1; }","duration":"200.013408ms","start":"2026-08-15T10:40:50.779483Z","end":"2026-08-15T10:40:50.979496Z","steps":["trace[2078492257] 'process raft request' (duration: 199.900866ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:40:58.89865Z","caller":"traceutil/trace.go:171","msg":"trace[1488479716] transaction","detail":"{read_only:false; response_revision:18573; number_of_response:1; }","duration":"133.216606ms","start":"2026-08-15T10:40:58.765411Z","end":"2026-08-15T10:40:58.898628Z","steps":["trace[1488479716] 'process raft request' (duration: 133.026892ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:40:58.961494Z","caller":"traceutil/trace.go:171","msg":"trace[1937858426] transaction","detail":"{read_only:false; response_revision:18574; number_of_response:1; }","duration":"195.342678ms","start":"2026-08-15T10:40:58.766129Z","end":"2026-08-15T10:40:58.961472Z","steps":["trace[1937858426] 'process raft request' (duration: 156.197987ms)","trace[1937858426] 'compare' (duration: 38.675502ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:40:58.961643Z","caller":"traceutil/trace.go:171","msg":"trace[2021770415] transaction","detail":"{read_only:false; response_revision:18575; number_of_response:1; }","duration":"169.397449ms","start":"2026-08-15T10:40:58.792234Z","end":"2026-08-15T10:40:58.961631Z","steps":["trace[2021770415] 'process raft request' (duration: 169.038282ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:42:07.76284Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-15T10:42:07.767908Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-15T10:42:07.768017Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-15T10:42:48.690512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17329} {"level":"info","ts":"2026-08-15T10:42:48.790462Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17329,"took":"96.829168ms","hash":154363216,"current-db-size-bytes":58634240,"current-db-size":"59 MB","current-db-size-in-use-bytes":34045952,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-15T10:42:48.790564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":154363216,"revision":17329,"compact-revision":14713} {"level":"info","ts":"2026-08-15T10:47:10.484071Z","caller":"traceutil/trace.go:171","msg":"trace[1261599558] transaction","detail":"{read_only:false; response_revision:21388; number_of_response:1; }","duration":"142.97769ms","start":"2026-08-15T10:47:10.341058Z","end":"2026-08-15T10:47:10.484036Z","steps":["trace[1261599558] 'process raft request' (duration: 142.769216ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:47:10.487556Z","caller":"traceutil/trace.go:171","msg":"trace[1304552979] transaction","detail":"{read_only:false; response_revision:21389; number_of_response:1; }","duration":"143.200903ms","start":"2026-08-15T10:47:10.344326Z","end":"2026-08-15T10:47:10.487526Z","steps":["trace[1304552979] 'process raft request' (duration: 142.640833ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:47:10.487742Z","caller":"traceutil/trace.go:171","msg":"trace[967189695] transaction","detail":"{read_only:false; response_revision:21390; number_of_response:1; }","duration":"116.290047ms","start":"2026-08-15T10:47:10.371442Z","end":"2026-08-15T10:47:10.487732Z","steps":["trace[967189695] 'process raft request' (duration: 115.650945ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:47:10.724291Z","caller":"traceutil/trace.go:171","msg":"trace[2061842479] transaction","detail":"{read_only:false; response_revision:21391; number_of_response:1; }","duration":"204.160272ms","start":"2026-08-15T10:47:10.520095Z","end":"2026-08-15T10:47:10.724255Z","steps":["trace[2061842479] 'process raft request' (duration: 159.490656ms)","trace[2061842479] 'compare' (duration: 44.467622ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:47:11.561295Z","caller":"traceutil/trace.go:171","msg":"trace[1544529717] transaction","detail":"{read_only:false; response_revision:21392; number_of_response:1; }","duration":"326.762451ms","start":"2026-08-15T10:47:11.234496Z","end":"2026-08-15T10:47:11.561258Z","steps":["trace[1544529717] 'process raft request' (duration: 326.518906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:47:11.56152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:47:11.234463Z","time spent":"326.933734ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T10:47:11.606015Z","caller":"traceutil/trace.go:171","msg":"trace[2063618230] linearizableReadLoop","detail":"{readStateIndex:22120; appliedIndex:22118; }","duration":"356.675245ms","start":"2026-08-15T10:47:11.2493Z","end":"2026-08-15T10:47:11.605975Z","steps":["trace[2063618230] 'read index received' (duration: 311.801885ms)","trace[2063618230] 'applied index is now lower than readState.Index' (duration: 44.871569ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:47:11.606093Z","caller":"traceutil/trace.go:171","msg":"trace[1500156488] transaction","detail":"{read_only:false; response_revision:21393; number_of_response:1; }","duration":"370.956048ms","start":"2026-08-15T10:47:11.235114Z","end":"2026-08-15T10:47:11.60607Z","steps":["trace[1500156488] 'process raft request' (duration: 370.643742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:47:11.6062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:47:11.235088Z","time spent":"371.05934ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T10:47:11.606416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.065612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-15T10:47:11.606445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.332957ms","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-15T10:47:11.606504Z","caller":"traceutil/trace.go:171","msg":"trace[1846159262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21393; }","duration":"357.244195ms","start":"2026-08-15T10:47:11.249238Z","end":"2026-08-15T10:47:11.606482Z","steps":["trace[1846159262] 'agreement among raft nodes before linearized reading' (duration: 356.920029ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:47:11.606554Z","caller":"traceutil/trace.go:171","msg":"trace[1464597231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21393; }","duration":"202.50207ms","start":"2026-08-15T10:47:11.404028Z","end":"2026-08-15T10:47:11.60653Z","steps":["trace[1464597231] 'agreement among raft nodes before linearized reading' (duration: 202.195584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:47:11.606578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:47:11.249218Z","time spent":"357.343087ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-15T10:47:11.769303Z","caller":"traceutil/trace.go:171","msg":"trace[76946065] transaction","detail":"{read_only:false; response_revision:21394; number_of_response:1; }","duration":"157.205392ms","start":"2026-08-15T10:47:11.612064Z","end":"2026-08-15T10:47:11.769269Z","steps":["trace[76946065] 'process raft request' (duration: 156.961717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:47:12.03926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.398982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T10:47:12.039412Z","caller":"traceutil/trace.go:171","msg":"trace[1104537991] linearizableReadLoop","detail":"{readStateIndex:22122; appliedIndex:22120; }","duration":"371.385215ms","start":"2026-08-15T10:47:11.667999Z","end":"2026-08-15T10:47:12.039384Z","steps":["trace[1104537991] 'read index received' (duration: 101.064776ms)","trace[1104537991] 'applied index is now lower than readState.Index' (duration: 270.317749ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:47:12.039501Z","caller":"traceutil/trace.go:171","msg":"trace[1816968598] transaction","detail":"{read_only:false; response_revision:21395; number_of_response:1; }","duration":"426.677834ms","start":"2026-08-15T10:47:11.612782Z","end":"2026-08-15T10:47:12.03946Z","steps":["trace[1816968598] 'process raft request' (duration: 289.926965ms)","trace[1816968598] 'compare' (duration: 136.195039ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T10:47:12.039576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.565528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-15T10:47:12.039631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.596043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T10:47:12.039668Z","caller":"traceutil/trace.go:171","msg":"trace[991471260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21395; }","duration":"371.695501ms","start":"2026-08-15T10:47:11.667954Z","end":"2026-08-15T10:47:12.039649Z","steps":["trace[991471260] 'agreement among raft nodes before linearized reading' (duration: 371.545588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:47:12.03971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.680927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-15T10:47:12.039745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:47:11.667936Z","time spent":"371.783842ms","remote":"127.0.0.1:47572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-15T10:47:12.039752Z","caller":"traceutil/trace.go:171","msg":"trace[1420282761] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:21395; }","duration":"366.767836ms","start":"2026-08-15T10:47:11.672941Z","end":"2026-08-15T10:47:12.039709Z","steps":["trace[1420282761] 'agreement among raft nodes before linearized reading' (duration: 366.600423ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:47:12.03977Z","caller":"traceutil/trace.go:171","msg":"trace[306280874] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:21395; }","duration":"161.78349ms","start":"2026-08-15T10:47:11.877971Z","end":"2026-08-15T10:47:12.039754Z","steps":["trace[306280874] 'agreement among raft nodes before linearized reading' (duration: 161.566775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:47:12.039801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:47:11.672916Z","time spent":"366.874599ms","remote":"127.0.0.1:39086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-08-15T10:47:12.039825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.429996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-15T10:47:12.039875Z","caller":"traceutil/trace.go:171","msg":"trace[2029934371] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:21395; }","duration":"182.544749ms","start":"2026-08-15T10:47:11.857315Z","end":"2026-08-15T10:47:12.03986Z","steps":["trace[2029934371] 'agreement among raft nodes before linearized reading' (duration: 182.375905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:47:12.039734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T10:47:11.612756Z","time spent":"426.834578ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T10:47:12.039888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.71006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-zmgzn.18cbf482cb166f41\" ","response":"range_response_count:1 size:1395"} {"level":"info","ts":"2026-08-15T10:47:12.039958Z","caller":"traceutil/trace.go:171","msg":"trace[1506642622] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-zmgzn.18cbf482cb166f41; range_end:; response_count:1; response_revision:21395; }","duration":"290.831152ms","start":"2026-08-15T10:47:11.749106Z","end":"2026-08-15T10:47:12.039937Z","steps":["trace[1506642622] 'agreement among raft nodes before linearized reading' (duration: 290.590297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T10:47:12.040183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.857766ms","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-15T10:47:12.040877Z","caller":"traceutil/trace.go:171","msg":"trace[513614780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21395; }","duration":"109.05454ms","start":"2026-08-15T10:47:11.931235Z","end":"2026-08-15T10:47:12.040289Z","steps":["trace[513614780] 'agreement among raft nodes before linearized reading' (duration: 108.314935ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:47:48.699637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19635} {"level":"info","ts":"2026-08-15T10:47:48.815077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19635,"took":"111.594898ms","hash":245400601,"current-db-size-bytes":58634240,"current-db-size":"59 MB","current-db-size-in-use-bytes":30007296,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-15T10:47:48.815136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":245400601,"revision":19635,"compact-revision":17329} {"level":"info","ts":"2026-08-15T10:51:22.023791Z","caller":"traceutil/trace.go:171","msg":"trace[238270079] transaction","detail":"{read_only:false; response_revision:23956; number_of_response:1; }","duration":"112.693367ms","start":"2026-08-15T10:51:21.91105Z","end":"2026-08-15T10:51:22.023744Z","steps":["trace[238270079] 'process raft request' (duration: 71.765363ms)","trace[238270079] 'compare' (duration: 40.429665ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T10:51:22.294879Z","caller":"traceutil/trace.go:171","msg":"trace[1463097172] transaction","detail":"{read_only:false; response_revision:23957; number_of_response:1; }","duration":"183.066485ms","start":"2026-08-15T10:51:22.111792Z","end":"2026-08-15T10:51:22.294858Z","steps":["trace[1463097172] 'process raft request' (duration: 182.914922ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:51:22.322763Z","caller":"traceutil/trace.go:171","msg":"trace[189760343] transaction","detail":"{read_only:false; response_revision:23958; number_of_response:1; }","duration":"155.441896ms","start":"2026-08-15T10:51:22.167281Z","end":"2026-08-15T10:51:22.322723Z","steps":["trace[189760343] 'process raft request' (duration: 155.223002ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T10:52:48.71458Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21799} {"level":"info","ts":"2026-08-15T10:52:48.826388Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21799,"took":"104.996287ms","hash":1998095104,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":43577344,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-15T10:52:48.826469Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1998095104,"revision":21799,"compact-revision":19635} {"level":"info","ts":"2026-08-15T10:55:31.893683Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000696e.wal"} {"level":"info","ts":"2026-08-15T10:57:48.725206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24878} {"level":"info","ts":"2026-08-15T10:57:48.867993Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24878,"took":"137.865195ms","hash":1906000291,"current-db-size-bytes":59056128,"current-db-size":"59 MB","current-db-size-in-use-bytes":31076352,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-15T10:57:48.868047Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1906000291,"revision":24878,"compact-revision":21799}