{"level":"warn","ts":"2026-09-01T04:12:34.102694Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T04:12:34.102822Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.4:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.4:2380","--initial-cluster=instance=https://162.253.55.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.4: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-09-01T04:12:34.102897Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T04:12:34.102909Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.4:2380"]} {"level":"info","ts":"2026-09-01T04:12:34.10293Z","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-09-01T04:12:34.103594Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"]} {"level":"info","ts":"2026-09-01T04:12:34.10368Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.4:2380"],"listen-peer-urls":["https://162.253.55.4:2380"],"advertise-client-urls":["https://162.253.55.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.4: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-09-01T04:12:34.107842Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.877707ms"} {"level":"info","ts":"2026-09-01T04:12:34.118767Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"caafea9f270da559","cluster-id":"271ff399bb4addea"} {"level":"info","ts":"2026-09-01T04:12:34.118845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T04:12:34.118873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became follower at term 0"} {"level":"info","ts":"2026-09-01T04:12:34.118886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft caafea9f270da559 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T04:12:34.118896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became follower at term 1"} {"level":"info","ts":"2026-09-01T04:12:34.11893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=(14605150085861713241)"} {"level":"warn","ts":"2026-09-01T04:12:34.1261Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T04:12:34.128512Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T04:12:34.130072Z","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-09-01T04:12:34.133071Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"caafea9f270da559","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T04:12:34.133455Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"caafea9f270da559","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T04:12:34.13348Z","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-09-01T04:12:34.133541Z","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-09-01T04:12:34.133553Z","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-09-01T04:12:34.135139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=(14605150085861713241)"} {"level":"info","ts":"2026-09-01T04:12:34.135266Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"271ff399bb4addea","local-member-id":"caafea9f270da559","added-peer-id":"caafea9f270da559","added-peer-peer-urls":["https://162.253.55.4:2380"]} {"level":"info","ts":"2026-09-01T04:12:34.137083Z","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-09-01T04:12:34.1372Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.4:2380"} {"level":"info","ts":"2026-09-01T04:12:34.137223Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.4:2380"} {"level":"info","ts":"2026-09-01T04:12:34.137371Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"caafea9f270da559","initial-advertise-peer-urls":["https://162.253.55.4:2380"],"listen-peer-urls":["https://162.253.55.4:2380"],"advertise-client-urls":["https://162.253.55.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T04:12:34.137413Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T04:12:34.219513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T04:12:34.219574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T04:12:34.219595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 received MsgPreVoteResp from caafea9f270da559 at term 1"} {"level":"info","ts":"2026-09-01T04:12:34.219612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became candidate at term 2"} {"level":"info","ts":"2026-09-01T04:12:34.219623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 received MsgVoteResp from caafea9f270da559 at term 2"} {"level":"info","ts":"2026-09-01T04:12:34.219637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became leader at term 2"} {"level":"info","ts":"2026-09-01T04:12:34.21965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: caafea9f270da559 elected leader caafea9f270da559 at term 2"} {"level":"info","ts":"2026-09-01T04:12:34.220844Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:12:34.221572Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"caafea9f270da559","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.4:2379]}","request-path":"/0/members/caafea9f270da559/attributes","cluster-id":"271ff399bb4addea","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T04:12:34.221722Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T04:12:34.221759Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T04:12:34.22234Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"271ff399bb4addea","local-member-id":"caafea9f270da559","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:12:34.222466Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:12:34.222519Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:12:34.22403Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.4:2379"} {"level":"info","ts":"2026-09-01T04:12:34.224128Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T04:12:34.224144Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T04:12:34.224623Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T04:12:54.573152Z","caller":"traceutil/trace.go:171","msg":"trace[1176591026] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"150.401801ms","start":"2026-09-01T04:12:54.422724Z","end":"2026-09-01T04:12:54.573125Z","steps":["trace[1176591026] 'process raft request' (duration: 150.255627ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:13:18.780299Z","caller":"traceutil/trace.go:171","msg":"trace[930987919] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"274.098911ms","start":"2026-09-01T04:13:18.506169Z","end":"2026-09-01T04:13:18.780268Z","steps":["trace[930987919] 'process raft request' (duration: 241.969805ms)","trace[930987919] 'compare' (duration: 31.90573ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:13:18.846279Z","caller":"traceutil/trace.go:171","msg":"trace[1504602830] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"263.376166ms","start":"2026-09-01T04:13:18.582864Z","end":"2026-09-01T04:13:18.84624Z","steps":["trace[1504602830] 'process raft request' (duration: 257.814108ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:13:18.846304Z","caller":"traceutil/trace.go:171","msg":"trace[54937567] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"165.389647ms","start":"2026-09-01T04:13:18.680843Z","end":"2026-09-01T04:13:18.846232Z","steps":["trace[54937567] 'read index received' (duration: 67.365738ms)","trace[54937567] 'applied index is now lower than readState.Index' (duration: 98.020479ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:13:18.846558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.684778ms","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-09-01T04:13:18.846635Z","caller":"traceutil/trace.go:171","msg":"trace[339560805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"165.840222ms","start":"2026-09-01T04:13:18.680785Z","end":"2026-09-01T04:13:18.846625Z","steps":["trace[339560805] 'agreement among raft nodes before linearized reading' (duration: 165.601415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:13:19.528347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.4161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:13:19.528463Z","caller":"traceutil/trace.go:171","msg":"trace[865016265] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"603.113584ms","start":"2026-09-01T04:13:18.925328Z","end":"2026-09-01T04:13:19.528442Z","steps":["trace[865016265] 'process raft request' (duration: 306.067444ms)","trace[865016265] 'compare' (duration: 296.228835ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:13:19.528522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:13:18.925292Z","time spent":"603.204906ms","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-01T04:13:36.768422Z","caller":"traceutil/trace.go:171","msg":"trace[557670850] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"135.301904ms","start":"2026-09-01T04:13:36.633098Z","end":"2026-09-01T04:13:36.768399Z","steps":["trace[557670850] 'read index received' (duration: 135.019384ms)","trace[557670850] 'applied index is now lower than readState.Index' (duration: 281.53µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:13:36.768541Z","caller":"traceutil/trace.go:171","msg":"trace[985177517] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"171.965878ms","start":"2026-09-01T04:13:36.596563Z","end":"2026-09-01T04:13:36.768529Z","steps":["trace[985177517] 'process raft request' (duration: 171.653048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:13:36.768819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.26814ms","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-09-01T04:13:36.768912Z","caller":"traceutil/trace.go:171","msg":"trace[823750412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:807; }","duration":"104.399824ms","start":"2026-09-01T04:13:36.664482Z","end":"2026-09-01T04:13:36.768882Z","steps":["trace[823750412] 'agreement among raft nodes before linearized reading' (duration: 104.169667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:13:36.768942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.804259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:13:36.769054Z","caller":"traceutil/trace.go:171","msg":"trace[818241711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"135.947962ms","start":"2026-09-01T04:13:36.633081Z","end":"2026-09-01T04:13:36.769029Z","steps":["trace[818241711] 'agreement among raft nodes before linearized reading' (duration: 135.758577ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:14:05.656658Z","caller":"traceutil/trace.go:171","msg":"trace[636617848] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"112.463838ms","start":"2026-09-01T04:14:05.544164Z","end":"2026-09-01T04:14:05.656628Z","steps":["trace[636617848] 'process raft request' (duration: 111.893129ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:14:27.752205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.405808ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-09-01T04:14:54.856143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.690944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:14:54.856231Z","caller":"traceutil/trace.go:171","msg":"trace[1251747287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1893; }","duration":"224.83062ms","start":"2026-09-01T04:14:54.631381Z","end":"2026-09-01T04:14:54.856212Z","steps":["trace[1251747287] 'range keys from in-memory index tree' (duration: 224.615562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:14:54.856487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.598978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-01T04:14:54.856572Z","caller":"traceutil/trace.go:171","msg":"trace[1793557464] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1893; }","duration":"235.764054ms","start":"2026-09-01T04:14:54.620786Z","end":"2026-09-01T04:14:54.85655Z","steps":["trace[1793557464] 'range keys from in-memory index tree' (duration: 235.440912ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:14:55.266059Z","caller":"traceutil/trace.go:171","msg":"trace[123345764] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"269.305717ms","start":"2026-09-01T04:14:54.996728Z","end":"2026-09-01T04:14:55.266034Z","steps":["trace[123345764] 'process raft request' (duration: 269.128621ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:15:08.269798Z","caller":"traceutil/trace.go:171","msg":"trace[1499758488] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"179.417177ms","start":"2026-09-01T04:15:08.09036Z","end":"2026-09-01T04:15:08.269777Z","steps":["trace[1499758488] 'process raft request' (duration: 179.274991ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:15:45.89889Z","caller":"traceutil/trace.go:171","msg":"trace[858953126] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"188.510432ms","start":"2026-09-01T04:15:45.710344Z","end":"2026-09-01T04:15:45.898855Z","steps":["trace[858953126] 'process raft request' (duration: 188.313694ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:15:46.038022Z","caller":"traceutil/trace.go:171","msg":"trace[1776324362] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"311.922935ms","start":"2026-09-01T04:15:45.726077Z","end":"2026-09-01T04:15:46.038Z","steps":["trace[1776324362] 'process raft request' (duration: 256.053185ms)","trace[1776324362] 'compare' (duration: 55.712374ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:15:46.038214Z","caller":"traceutil/trace.go:171","msg":"trace[922498143] linearizableReadLoop","detail":"{readStateIndex:2303; appliedIndex:2301; }","duration":"311.82734ms","start":"2026-09-01T04:15:45.726351Z","end":"2026-09-01T04:15:46.038179Z","steps":["trace[922498143] 'read index received' (duration: 172.300446ms)","trace[922498143] 'applied index is now lower than readState.Index' (duration: 139.524864ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:15:46.038258Z","caller":"traceutil/trace.go:171","msg":"trace[1694969710] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"292.512798ms","start":"2026-09-01T04:15:45.745709Z","end":"2026-09-01T04:15:46.038222Z","steps":["trace[1694969710] 'process raft request' (duration: 292.254589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:15:46.038349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:15:45.726031Z","time spent":"312.057629ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:15:46.038401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.141735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T04:15:46.038443Z","caller":"traceutil/trace.go:171","msg":"trace[345142411] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2220; }","duration":"233.235888ms","start":"2026-09-01T04:15:45.805197Z","end":"2026-09-01T04:15:46.038432Z","steps":["trace[345142411] 'agreement among raft nodes before linearized reading' (duration: 233.146835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:15:46.038444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.695799ms","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-09-01T04:15:46.038489Z","caller":"traceutil/trace.go:171","msg":"trace[1657089174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2220; }","duration":"285.793183ms","start":"2026-09-01T04:15:45.752684Z","end":"2026-09-01T04:15:46.038477Z","steps":["trace[1657089174] 'agreement among raft nodes before linearized reading' (duration: 285.650257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:15:46.038604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.241336ms","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-09-01T04:15:46.038673Z","caller":"traceutil/trace.go:171","msg":"trace[2090931224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2220; }","duration":"312.324059ms","start":"2026-09-01T04:15:45.726332Z","end":"2026-09-01T04:15:46.038656Z","steps":["trace[2090931224] 'agreement among raft nodes before linearized reading' (duration: 311.944255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:15:46.038709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:15:45.726249Z","time spent":"312.453664ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-01T04:16:40.40951Z","caller":"traceutil/trace.go:171","msg":"trace[582582828] transaction","detail":"{read_only:false; response_revision:2684; number_of_response:1; }","duration":"110.107121ms","start":"2026-09-01T04:16:40.299378Z","end":"2026-09-01T04:16:40.409485Z","steps":["trace[582582828] 'process raft request' (duration: 96.657671ms)","trace[582582828] 'compare' (duration: 13.282294ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:16:40.409726Z","caller":"traceutil/trace.go:171","msg":"trace[713689971] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"104.21471ms","start":"2026-09-01T04:16:40.305485Z","end":"2026-09-01T04:16:40.4097Z","steps":["trace[713689971] 'process raft request' (duration: 103.960462ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:17:14.477937Z","caller":"traceutil/trace.go:171","msg":"trace[2124302468] transaction","detail":"{read_only:false; response_revision:2888; number_of_response:1; }","duration":"118.586394ms","start":"2026-09-01T04:17:14.359325Z","end":"2026-09-01T04:17:14.477911Z","steps":["trace[2124302468] 'process raft request' (duration: 118.442809ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:17:14.711699Z","caller":"traceutil/trace.go:171","msg":"trace[201341472] transaction","detail":"{read_only:false; response_revision:2892; number_of_response:1; }","duration":"134.801481ms","start":"2026-09-01T04:17:14.576871Z","end":"2026-09-01T04:17:14.711673Z","steps":["trace[201341472] 'process raft request' (duration: 67.690093ms)","trace[201341472] 'compare' (duration: 67.006264ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:17:14.714109Z","caller":"traceutil/trace.go:171","msg":"trace[1913019614] transaction","detail":"{read_only:false; response_revision:2893; number_of_response:1; }","duration":"102.42307ms","start":"2026-09-01T04:17:14.611625Z","end":"2026-09-01T04:17:14.714049Z","steps":["trace[1913019614] 'process raft request' (duration: 102.232475ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:18:11.506977Z","caller":"traceutil/trace.go:171","msg":"trace[1554301543] transaction","detail":"{read_only:false; response_revision:3202; number_of_response:1; }","duration":"123.066157ms","start":"2026-09-01T04:18:11.383879Z","end":"2026-09-01T04:18:11.506945Z","steps":["trace[1554301543] 'process raft request' (duration: 122.878262ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:20:02.039636Z","caller":"traceutil/trace.go:171","msg":"trace[891536896] transaction","detail":"{read_only:false; response_revision:3961; number_of_response:1; }","duration":"140.028326ms","start":"2026-09-01T04:20:01.899581Z","end":"2026-09-01T04:20:02.03961Z","steps":["trace[891536896] 'process raft request' (duration: 139.864081ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:21:46.939912Z","caller":"traceutil/trace.go:171","msg":"trace[1490917129] linearizableReadLoop","detail":"{readStateIndex:5257; appliedIndex:5256; }","duration":"178.404684ms","start":"2026-09-01T04:21:46.761472Z","end":"2026-09-01T04:21:46.939877Z","steps":["trace[1490917129] 'read index received' (duration: 162.110934ms)","trace[1490917129] 'applied index is now lower than readState.Index' (duration: 16.29256ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:21:46.940082Z","caller":"traceutil/trace.go:171","msg":"trace[1526692700] transaction","detail":"{read_only:false; response_revision:5063; number_of_response:1; }","duration":"192.62036ms","start":"2026-09-01T04:21:46.747443Z","end":"2026-09-01T04:21:46.940063Z","steps":["trace[1526692700] 'process raft request' (duration: 176.162695ms)","trace[1526692700] 'compare' (duration: 16.088004ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:21:46.940264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.776026ms","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-09-01T04:21:46.940299Z","caller":"traceutil/trace.go:171","msg":"trace[996032740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5063; }","duration":"178.848758ms","start":"2026-09-01T04:21:46.761436Z","end":"2026-09-01T04:21:46.940284Z","steps":["trace[996032740] 'agreement among raft nodes before linearized reading' (duration: 178.714624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:21:46.941051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.725219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210282"} {"level":"info","ts":"2026-09-01T04:21:46.94112Z","caller":"traceutil/trace.go:171","msg":"trace[697778751] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5063; }","duration":"113.830163ms","start":"2026-09-01T04:21:46.827275Z","end":"2026-09-01T04:21:46.941105Z","steps":["trace[697778751] 'agreement among raft nodes before linearized reading' (duration: 112.803561ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:21:51.421736Z","caller":"traceutil/trace.go:171","msg":"trace[1007469845] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"125.760169ms","start":"2026-09-01T04:21:51.295946Z","end":"2026-09-01T04:21:51.421706Z","steps":["trace[1007469845] 'process raft request' (duration: 125.428469ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:01.978566Z","caller":"traceutil/trace.go:171","msg":"trace[1287566744] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"108.421587ms","start":"2026-09-01T04:22:01.870107Z","end":"2026-09-01T04:22:01.978528Z","steps":["trace[1287566744] 'process raft request' (duration: 108.276292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:05.392509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.331104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:22:05.392658Z","caller":"traceutil/trace.go:171","msg":"trace[1688776351] linearizableReadLoop","detail":"{readStateIndex:5733; appliedIndex:5732; }","duration":"185.716118ms","start":"2026-09-01T04:22:05.206914Z","end":"2026-09-01T04:22:05.39263Z","steps":["trace[1688776351] 'read index received' (duration: 38.941µs)","trace[1688776351] 'applied index is now lower than readState.Index' (duration: 185.675107ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:22:05.392774Z","caller":"traceutil/trace.go:171","msg":"trace[1810149366] transaction","detail":"{read_only:false; response_revision:5522; number_of_response:1; }","duration":"318.659437ms","start":"2026-09-01T04:22:05.074098Z","end":"2026-09-01T04:22:05.392758Z","steps":["trace[1810149366] 'process raft request' (duration: 33.988183ms)","trace[1810149366] 'compare' (duration: 284.114137ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:22:05.39285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:05.074084Z","time spent":"318.72941ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:22:05.392909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.995936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-57xts\" ","response":"range_response_count:1 size:5850"} {"level":"info","ts":"2026-09-01T04:22:05.39296Z","caller":"traceutil/trace.go:171","msg":"trace[1383336697] range","detail":"{range_begin:/registry/pods/monitoring/vector-57xts; range_end:; response_count:1; response_revision:5522; }","duration":"186.039268ms","start":"2026-09-01T04:22:05.206903Z","end":"2026-09-01T04:22:05.392942Z","steps":["trace[1383336697] 'agreement among raft nodes before linearized reading' (duration: 185.907874ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:05.392889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.437906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T04:22:05.393108Z","caller":"traceutil/trace.go:171","msg":"trace[2134887136] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5522; }","duration":"165.708974ms","start":"2026-09-01T04:22:05.227382Z","end":"2026-09-01T04:22:05.393091Z","steps":["trace[2134887136] 'agreement among raft nodes before linearized reading' (duration: 165.432056ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:14.188247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.767518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:22:14.188429Z","caller":"traceutil/trace.go:171","msg":"trace[678641210] transaction","detail":"{read_only:false; response_revision:5689; number_of_response:1; }","duration":"136.138957ms","start":"2026-09-01T04:22:14.052276Z","end":"2026-09-01T04:22:14.188415Z","steps":["trace[678641210] 'process raft request' (duration: 136.066885ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:14.188591Z","caller":"traceutil/trace.go:171","msg":"trace[495961670] transaction","detail":"{read_only:false; response_revision:5688; number_of_response:1; }","duration":"140.186971ms","start":"2026-09-01T04:22:14.048397Z","end":"2026-09-01T04:22:14.188584Z","steps":["trace[495961670] 'process raft request' (duration: 30.019671ms)","trace[495961670] 'compare' (duration: 109.681626ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:22:14.188701Z","caller":"traceutil/trace.go:171","msg":"trace[584488057] transaction","detail":"{read_only:false; response_revision:5690; number_of_response:1; }","duration":"125.17301ms","start":"2026-09-01T04:22:14.063495Z","end":"2026-09-01T04:22:14.188668Z","steps":["trace[584488057] 'process raft request' (duration: 124.883591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:14.682499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.958296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-64e73a5a-526b-47ff-adec-8850b1e266b9\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-09-01T04:22:14.682599Z","caller":"traceutil/trace.go:171","msg":"trace[124372309] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-64e73a5a-526b-47ff-adec-8850b1e266b9; range_end:; response_count:1; response_revision:5690; }","duration":"392.09418ms","start":"2026-09-01T04:22:14.290471Z","end":"2026-09-01T04:22:14.682565Z","steps":["trace[124372309] 'range keys from in-memory index tree' (duration: 391.74652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:14.682631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:14.290458Z","time spent":"392.165832ms","remote":"127.0.0.1:59758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-64e73a5a-526b-47ff-adec-8850b1e266b9\" "} {"level":"warn","ts":"2026-09-01T04:22:14.6829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.004684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-01T04:22:14.682983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.668495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T04:22:14.68302Z","caller":"traceutil/trace.go:171","msg":"trace[1591231278] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5690; }","duration":"300.162329ms","start":"2026-09-01T04:22:14.382831Z","end":"2026-09-01T04:22:14.682993Z","steps":["trace[1591231278] 'count revisions from in-memory index tree' (duration: 299.669004ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:14.683112Z","caller":"traceutil/trace.go:171","msg":"trace[1578261937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5690; }","duration":"153.84052ms","start":"2026-09-01T04:22:14.529247Z","end":"2026-09-01T04:22:14.683088Z","steps":["trace[1578261937] 'range keys from in-memory index tree' (duration: 153.396585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:14.683122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:14.382811Z","time spent":"300.294492ms","remote":"127.0.0.1:59930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":8,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-09-01T04:22:14.682993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.800938ms","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-09-01T04:22:14.68337Z","caller":"traceutil/trace.go:171","msg":"trace[1667724748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5690; }","duration":"257.240662ms","start":"2026-09-01T04:22:14.426115Z","end":"2026-09-01T04:22:14.683355Z","steps":["trace[1667724748] 'range keys from in-memory index tree' (duration: 256.573081ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:34.45475Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2999} {"level":"info","ts":"2026-09-01T04:22:34.537753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2999,"took":"81.312726ms","hash":3482001282,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":32452608,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T04:22:34.537838Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3482001282,"revision":2999,"compact-revision":-1} {"level":"info","ts":"2026-09-01T04:27:34.460573Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5926} {"level":"info","ts":"2026-09-01T04:27:34.533828Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5926,"took":"71.914077ms","hash":1219649653,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":25997312,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T04:27:34.533904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1219649653,"revision":5926,"compact-revision":2999} {"level":"info","ts":"2026-09-01T04:28:47.083554Z","caller":"traceutil/trace.go:171","msg":"trace[1101804531] linearizableReadLoop","detail":"{readStateIndex:8980; appliedIndex:8979; }","duration":"179.628111ms","start":"2026-09-01T04:28:46.903886Z","end":"2026-09-01T04:28:47.083514Z","steps":["trace[1101804531] 'read index received' (duration: 179.464496ms)","trace[1101804531] 'applied index is now lower than readState.Index' (duration: 162.675µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:28:47.083765Z","caller":"traceutil/trace.go:171","msg":"trace[1495380582] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"183.730608ms","start":"2026-09-01T04:28:46.900026Z","end":"2026-09-01T04:28:47.083757Z","steps":["trace[1495380582] 'process raft request' (duration: 183.381846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.083895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.108603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T04:28:47.083958Z","caller":"traceutil/trace.go:171","msg":"trace[2133855559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8669; }","duration":"176.31952ms","start":"2026-09-01T04:28:46.907626Z","end":"2026-09-01T04:28:47.083946Z","steps":["trace[2133855559] 'agreement among raft nodes before linearized reading' (duration: 176.164255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.084005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.027173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:28:47.084035Z","caller":"traceutil/trace.go:171","msg":"trace[720787887] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8669; }","duration":"134.086964ms","start":"2026-09-01T04:28:46.949938Z","end":"2026-09-01T04:28:47.084025Z","steps":["trace[720787887] 'agreement among raft nodes before linearized reading' (duration: 134.024832ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.084125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.819956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:28:47.084151Z","caller":"traceutil/trace.go:171","msg":"trace[1434257356] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8669; }","duration":"147.885177ms","start":"2026-09-01T04:28:46.936258Z","end":"2026-09-01T04:28:47.084144Z","steps":["trace[1434257356] 'agreement among raft nodes before linearized reading' (duration: 147.844556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.084152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.157072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:28:47.084172Z","caller":"traceutil/trace.go:171","msg":"trace[713545855] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8669; }","duration":"113.179062ms","start":"2026-09-01T04:28:46.970987Z","end":"2026-09-01T04:28:47.084166Z","steps":["trace[713545855] 'agreement among raft nodes before linearized reading' (duration: 113.143461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.084263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.962641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:28:47.084272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.001718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:28:47.084285Z","caller":"traceutil/trace.go:171","msg":"trace[36157817] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8669; }","duration":"149.006552ms","start":"2026-09-01T04:28:46.93527Z","end":"2026-09-01T04:28:47.084277Z","steps":["trace[36157817] 'agreement among raft nodes before linearized reading' (duration: 148.974701ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:28:47.084294Z","caller":"traceutil/trace.go:171","msg":"trace[1310193018] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8669; }","duration":"114.04931ms","start":"2026-09-01T04:28:46.970239Z","end":"2026-09-01T04:28:47.084288Z","steps":["trace[1310193018] 'agreement among raft nodes before linearized reading' (duration: 114.015999ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.084304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.422366ms","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-09-01T04:28:47.084344Z","caller":"traceutil/trace.go:171","msg":"trace[2113673722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8669; }","duration":"180.502718ms","start":"2026-09-01T04:28:46.903831Z","end":"2026-09-01T04:28:47.084334Z","steps":["trace[2113673722] 'agreement among raft nodes before linearized reading' (duration: 180.407665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.084377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.111841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:28:47.084396Z","caller":"traceutil/trace.go:171","msg":"trace[1483495766] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8669; }","duration":"114.167273ms","start":"2026-09-01T04:28:46.970223Z","end":"2026-09-01T04:28:47.084391Z","steps":["trace[1483495766] 'agreement among raft nodes before linearized reading' (duration: 114.137372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.08442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.420688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:28:47.084444Z","caller":"traceutil/trace.go:171","msg":"trace[499509835] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8669; }","duration":"169.459129ms","start":"2026-09-01T04:28:46.914975Z","end":"2026-09-01T04:28:47.084434Z","steps":["trace[499509835] 'agreement among raft nodes before linearized reading' (duration: 169.431398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.084476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.899138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:28:47.084494Z","caller":"traceutil/trace.go:171","msg":"trace[1701135556] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8669; }","duration":"133.93737ms","start":"2026-09-01T04:28:46.950551Z","end":"2026-09-01T04:28:47.084489Z","steps":["trace[1701135556] 'agreement among raft nodes before linearized reading' (duration: 133.905689ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.084585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.607764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:28:47.0846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.540598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:28:47.084614Z","caller":"traceutil/trace.go:171","msg":"trace[1497007095] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8669; }","duration":"170.679966ms","start":"2026-09-01T04:28:46.913928Z","end":"2026-09-01T04:28:47.084608Z","steps":["trace[1497007095] 'agreement among raft nodes before linearized reading' (duration: 170.632055ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:28:47.084621Z","caller":"traceutil/trace.go:171","msg":"trace[694041188] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8669; }","duration":"134.584889ms","start":"2026-09-01T04:28:46.950028Z","end":"2026-09-01T04:28:47.084613Z","steps":["trace[694041188] 'agreement among raft nodes before linearized reading' (duration: 134.556158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.084709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.828201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:28:47.084732Z","caller":"traceutil/trace.go:171","msg":"trace[923235357] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8669; }","duration":"170.873313ms","start":"2026-09-01T04:28:46.913853Z","end":"2026-09-01T04:28:47.084726Z","steps":["trace[923235357] 'agreement among raft nodes before linearized reading' (duration: 170.840882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.084394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.279881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:28:47.084855Z","caller":"traceutil/trace.go:171","msg":"trace[528336182] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8669; }","duration":"149.740244ms","start":"2026-09-01T04:28:46.935092Z","end":"2026-09-01T04:28:47.084832Z","steps":["trace[528336182] 'agreement among raft nodes before linearized reading' (duration: 149.2814ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:28:47.964343Z","caller":"traceutil/trace.go:171","msg":"trace[618459013] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"314.823698ms","start":"2026-09-01T04:28:47.649496Z","end":"2026-09-01T04:28:47.96432Z","steps":["trace[618459013] 'process raft request' (duration: 314.528279ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:28:47.964384Z","caller":"traceutil/trace.go:171","msg":"trace[1772279006] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"225.594561ms","start":"2026-09-01T04:28:47.738777Z","end":"2026-09-01T04:28:47.964371Z","steps":["trace[1772279006] 'process raft request' (duration: 225.408006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.964513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:28:47.649474Z","time spent":"314.954803ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:28:47.964585Z","caller":"traceutil/trace.go:171","msg":"trace[148295770] linearizableReadLoop","detail":"{readStateIndex:8985; appliedIndex:8984; }","duration":"273.931835ms","start":"2026-09-01T04:28:47.690643Z","end":"2026-09-01T04:28:47.964575Z","steps":["trace[148295770] 'read index received' (duration: 205.032881ms)","trace[148295770] 'applied index is now lower than readState.Index' (duration: 68.898164ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:28:47.96434Z","caller":"traceutil/trace.go:171","msg":"trace[1760453748] transaction","detail":"{read_only:false; response_revision:8675; number_of_response:1; }","duration":"203.38509ms","start":"2026-09-01T04:28:47.760914Z","end":"2026-09-01T04:28:47.964299Z","steps":["trace[1760453748] 'process raft request' (duration: 203.308628ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:28:47.964702Z","caller":"traceutil/trace.go:171","msg":"trace[1748718856] transaction","detail":"{read_only:false; response_revision:8676; number_of_response:1; }","duration":"182.770538ms","start":"2026-09-01T04:28:47.781923Z","end":"2026-09-01T04:28:47.964694Z","steps":["trace[1748718856] 'process raft request' (duration: 182.330834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.964858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.211293ms","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-09-01T04:28:47.964886Z","caller":"traceutil/trace.go:171","msg":"trace[100677319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8676; }","duration":"274.307686ms","start":"2026-09-01T04:28:47.69057Z","end":"2026-09-01T04:28:47.964878Z","steps":["trace[100677319] 'agreement among raft nodes before linearized reading' (duration: 274.202743ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:28:47.976811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.900159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:28:47.976889Z","caller":"traceutil/trace.go:171","msg":"trace[529630541] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:8677; }","duration":"135.032073ms","start":"2026-09-01T04:28:47.84184Z","end":"2026-09-01T04:28:47.976872Z","steps":["trace[529630541] 'agreement among raft nodes before linearized reading' (duration: 134.868388ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:28:48.619674Z","caller":"traceutil/trace.go:171","msg":"trace[1349965156] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"118.953129ms","start":"2026-09-01T04:28:48.500692Z","end":"2026-09-01T04:28:48.619646Z","steps":["trace[1349965156] 'process raft request' (duration: 118.605879ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:12.782773Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023cf.wal"} {"level":"info","ts":"2026-09-01T04:29:17.526915Z","caller":"traceutil/trace.go:171","msg":"trace[1750722623] linearizableReadLoop","detail":"{readStateIndex:9203; appliedIndex:9202; }","duration":"198.926263ms","start":"2026-09-01T04:29:17.327962Z","end":"2026-09-01T04:29:17.526888Z","steps":["trace[1750722623] 'read index received' (duration: 198.718697ms)","trace[1750722623] 'applied index is now lower than readState.Index' (duration: 206.536µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:29:17.527048Z","caller":"traceutil/trace.go:171","msg":"trace[1556014822] transaction","detail":"{read_only:false; response_revision:8884; number_of_response:1; }","duration":"202.349048ms","start":"2026-09-01T04:29:17.324683Z","end":"2026-09-01T04:29:17.527032Z","steps":["trace[1556014822] 'process raft request' (duration: 202.063919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:17.527395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.545681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-01T04:29:17.527486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.516502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T04:29:17.528326Z","caller":"traceutil/trace.go:171","msg":"trace[214008161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8884; }","duration":"200.369037ms","start":"2026-09-01T04:29:17.327918Z","end":"2026-09-01T04:29:17.528287Z","steps":["trace[214008161] 'agreement among raft nodes before linearized reading' (duration: 199.292005ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:17.527538Z","caller":"traceutil/trace.go:171","msg":"trace[743483439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8884; }","duration":"197.728156ms","start":"2026-09-01T04:29:17.32979Z","end":"2026-09-01T04:29:17.527518Z","steps":["trace[743483439] 'agreement among raft nodes before linearized reading' (duration: 197.498589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:17.527446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.05606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T04:29:17.528714Z","caller":"traceutil/trace.go:171","msg":"trace[1583799618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8884; }","duration":"190.365021ms","start":"2026-09-01T04:29:17.33834Z","end":"2026-09-01T04:29:17.528705Z","steps":["trace[1583799618] 'agreement among raft nodes before linearized reading' (duration: 189.005009ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:17.80498Z","caller":"traceutil/trace.go:171","msg":"trace[1601072834] linearizableReadLoop","detail":"{readStateIndex:9206; appliedIndex:9203; }","duration":"171.190372ms","start":"2026-09-01T04:29:17.633768Z","end":"2026-09-01T04:29:17.804958Z","steps":["trace[1601072834] 'read index received' (duration: 169.220371ms)","trace[1601072834] 'applied index is now lower than readState.Index' (duration: 1.969151ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:29:17.805047Z","caller":"traceutil/trace.go:171","msg":"trace[289196049] transaction","detail":"{read_only:false; response_revision:8885; number_of_response:1; }","duration":"272.240992ms","start":"2026-09-01T04:29:17.532784Z","end":"2026-09-01T04:29:17.805025Z","steps":["trace[289196049] 'process raft request' (duration: 270.142077ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:17.805018Z","caller":"traceutil/trace.go:171","msg":"trace[686646200] transaction","detail":"{read_only:false; response_revision:8886; number_of_response:1; }","duration":"272.108479ms","start":"2026-09-01T04:29:17.532879Z","end":"2026-09-01T04:29:17.804987Z","steps":["trace[686646200] 'process raft request' (duration: 271.960904ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:17.805096Z","caller":"traceutil/trace.go:171","msg":"trace[2015301880] transaction","detail":"{read_only:false; response_revision:8887; number_of_response:1; }","duration":"272.123929ms","start":"2026-09-01T04:29:17.53296Z","end":"2026-09-01T04:29:17.805084Z","steps":["trace[2015301880] 'process raft request' (duration: 271.962194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:17.80532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.085689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:29:17.805381Z","caller":"traceutil/trace.go:171","msg":"trace[909971157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8887; }","duration":"171.196012ms","start":"2026-09-01T04:29:17.634169Z","end":"2026-09-01T04:29:17.805365Z","steps":["trace[909971157] 'agreement among raft nodes before linearized reading' (duration: 171.089249ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:17.805401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.629145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-09-01T04:29:17.805456Z","caller":"traceutil/trace.go:171","msg":"trace[776336210] range","detail":"{range_begin:/registry/masterleases/162.253.55.4; range_end:; response_count:1; response_revision:8887; }","duration":"171.718147ms","start":"2026-09-01T04:29:17.633723Z","end":"2026-09-01T04:29:17.805442Z","steps":["trace[776336210] 'agreement among raft nodes before linearized reading' (duration: 171.550102ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:19.669432Z","caller":"traceutil/trace.go:171","msg":"trace[595214452] linearizableReadLoop","detail":"{readStateIndex:9215; appliedIndex:9214; }","duration":"139.378227ms","start":"2026-09-01T04:29:19.530025Z","end":"2026-09-01T04:29:19.669403Z","steps":["trace[595214452] 'read index received' (duration: 139.270503ms)","trace[595214452] 'applied index is now lower than readState.Index' (duration: 106.514µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:29:19.669459Z","caller":"traceutil/trace.go:171","msg":"trace[515206030] transaction","detail":"{read_only:false; response_revision:8895; number_of_response:1; }","duration":"148.384912ms","start":"2026-09-01T04:29:19.521038Z","end":"2026-09-01T04:29:19.669423Z","steps":["trace[515206030] 'process raft request' (duration: 148.185895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:19.669755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.702676ms","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-09-01T04:29:19.669808Z","caller":"traceutil/trace.go:171","msg":"trace[1356521547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8895; }","duration":"139.82244ms","start":"2026-09-01T04:29:19.529972Z","end":"2026-09-01T04:29:19.669795Z","steps":["trace[1356521547] 'agreement among raft nodes before linearized reading' (duration: 139.601664ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:19.950003Z","caller":"traceutil/trace.go:171","msg":"trace[1836617120] transaction","detail":"{read_only:false; response_revision:8896; number_of_response:1; }","duration":"295.076174ms","start":"2026-09-01T04:29:19.654845Z","end":"2026-09-01T04:29:19.949921Z","steps":["trace[1836617120] 'process raft request' (duration: 294.759734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:19.950204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.830471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:29:19.950328Z","caller":"traceutil/trace.go:171","msg":"trace[1621279644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8896; }","duration":"301.996166ms","start":"2026-09-01T04:29:19.648303Z","end":"2026-09-01T04:29:19.950299Z","steps":["trace[1621279644] 'agreement among raft nodes before linearized reading' (duration: 301.745959ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:19.950291Z","caller":"traceutil/trace.go:171","msg":"trace[1532667954] linearizableReadLoop","detail":"{readStateIndex:9216; appliedIndex:9215; }","duration":"280.3038ms","start":"2026-09-01T04:29:19.669506Z","end":"2026-09-01T04:29:19.94981Z","steps":["trace[1532667954] 'read index received' (duration: 279.96912ms)","trace[1532667954] 'applied index is now lower than readState.Index' (duration: 333.56µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:29:19.95039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:29:19.648285Z","time spent":"302.090659ms","remote":"127.0.0.1:59590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T04:29:19.970013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.846924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T04:29:19.970083Z","caller":"traceutil/trace.go:171","msg":"trace[1476978490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8897; }","duration":"159.939617ms","start":"2026-09-01T04:29:19.810129Z","end":"2026-09-01T04:29:19.970069Z","steps":["trace[1476978490] 'agreement among raft nodes before linearized reading' (duration: 159.72783ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:19.970083Z","caller":"traceutil/trace.go:171","msg":"trace[1521647573] transaction","detail":"{read_only:false; response_revision:8897; number_of_response:1; }","duration":"295.735344ms","start":"2026-09-01T04:29:19.674336Z","end":"2026-09-01T04:29:19.970071Z","steps":["trace[1521647573] 'process raft request' (duration: 295.370403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:19.970013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.012489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T04:29:19.970186Z","caller":"traceutil/trace.go:171","msg":"trace[1449555747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8897; }","duration":"160.214175ms","start":"2026-09-01T04:29:19.809963Z","end":"2026-09-01T04:29:19.970177Z","steps":["trace[1449555747] 'agreement among raft nodes before linearized reading' (duration: 159.906436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:19.970288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.305128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T04:29:19.970712Z","caller":"traceutil/trace.go:171","msg":"trace[1260757111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8897; }","duration":"160.762553ms","start":"2026-09-01T04:29:19.809921Z","end":"2026-09-01T04:29:19.970684Z","steps":["trace[1260757111] 'agreement among raft nodes before linearized reading' (duration: 160.167924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:28.149469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.33537ms","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-09-01T04:29:28.149573Z","caller":"traceutil/trace.go:171","msg":"trace[1585345274] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8939; }","duration":"117.490145ms","start":"2026-09-01T04:29:28.032066Z","end":"2026-09-01T04:29:28.149556Z","steps":["trace[1585345274] 'range keys from in-memory index tree' (duration: 117.083423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:28.149712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.690037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-01T04:29:28.149732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.395777ms","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-09-01T04:29:28.149792Z","caller":"traceutil/trace.go:171","msg":"trace[1861020785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8939; }","duration":"125.821921ms","start":"2026-09-01T04:29:28.023953Z","end":"2026-09-01T04:29:28.149775Z","steps":["trace[1861020785] 'range keys from in-memory index tree' (duration: 125.513891ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:28.149801Z","caller":"traceutil/trace.go:171","msg":"trace[1433255804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8939; }","duration":"138.51166ms","start":"2026-09-01T04:29:28.011272Z","end":"2026-09-01T04:29:28.149784Z","steps":["trace[1433255804] 'range keys from in-memory index tree' (duration: 138.204271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:28.149801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.073749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-09-01T04:29:28.149828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.431308ms","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-09-01T04:29:28.149885Z","caller":"traceutil/trace.go:171","msg":"trace[214830821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8939; }","duration":"138.52724ms","start":"2026-09-01T04:29:28.011344Z","end":"2026-09-01T04:29:28.149871Z","steps":["trace[214830821] 'range keys from in-memory index tree' (duration: 138.057196ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:28.149916Z","caller":"traceutil/trace.go:171","msg":"trace[2114990735] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8939; }","duration":"100.197723ms","start":"2026-09-01T04:29:28.049666Z","end":"2026-09-01T04:29:28.149864Z","steps":["trace[2114990735] 'range keys from in-memory index tree' (duration: 99.899185ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:30.459371Z","caller":"traceutil/trace.go:171","msg":"trace[1594223007] transaction","detail":"{read_only:false; response_revision:8971; number_of_response:1; }","duration":"149.541218ms","start":"2026-09-01T04:29:30.309804Z","end":"2026-09-01T04:29:30.459345Z","steps":["trace[1594223007] 'process raft request' (duration: 149.389594ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:29:30.499502Z","caller":"traceutil/trace.go:171","msg":"trace[1211932306] linearizableReadLoop","detail":"{readStateIndex:9295; appliedIndex:9293; }","duration":"123.021384ms","start":"2026-09-01T04:29:30.376459Z","end":"2026-09-01T04:29:30.49948Z","steps":["trace[1211932306] 'read index received' (duration: 82.799741ms)","trace[1211932306] 'applied index is now lower than readState.Index' (duration: 40.220493ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:29:30.499596Z","caller":"traceutil/trace.go:171","msg":"trace[730864593] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8972; }","duration":"165.999014ms","start":"2026-09-01T04:29:30.333582Z","end":"2026-09-01T04:29:30.499581Z","steps":["trace[730864593] 'process raft request' (duration: 165.793447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:30.499636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.160049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T04:29:30.499663Z","caller":"traceutil/trace.go:171","msg":"trace[1473121775] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:8972; }","duration":"123.232571ms","start":"2026-09-01T04:29:30.37642Z","end":"2026-09-01T04:29:30.499652Z","steps":["trace[1473121775] 'agreement among raft nodes before linearized reading' (duration: 123.147339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:29:43.6093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.624089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-01T04:29:43.6094Z","caller":"traceutil/trace.go:171","msg":"trace[108649228] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9099; }","duration":"102.770304ms","start":"2026-09-01T04:29:43.506611Z","end":"2026-09-01T04:29:43.609381Z","steps":["trace[108649228] 'range keys from in-memory index tree' (duration: 102.438443ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:30:34.63865Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T04:30:34.643887Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T04:30:34.643952Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-01T04:30:46.776382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.957308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:30:46.776487Z","caller":"traceutil/trace.go:171","msg":"trace[1170413860] linearizableReadLoop","detail":"{readStateIndex:10090; appliedIndex:10089; }","duration":"143.290996ms","start":"2026-09-01T04:30:46.633184Z","end":"2026-09-01T04:30:46.776475Z","steps":["trace[1170413860] 'read index received' (duration: 27.211555ms)","trace[1170413860] 'applied index is now lower than readState.Index' (duration: 116.078641ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:30:46.776561Z","caller":"traceutil/trace.go:171","msg":"trace[140898234] transaction","detail":"{read_only:false; response_revision:9744; number_of_response:1; }","duration":"146.232206ms","start":"2026-09-01T04:30:46.630307Z","end":"2026-09-01T04:30:46.776539Z","steps":["trace[140898234] 'process raft request' (duration: 30.044492ms)","trace[140898234] 'compare' (duration: 115.855764ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:30:46.776691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.675488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T04:30:46.776717Z","caller":"traceutil/trace.go:171","msg":"trace[348885945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9744; }","duration":"142.724349ms","start":"2026-09-01T04:30:46.633985Z","end":"2026-09-01T04:30:46.776709Z","steps":["trace[348885945] 'agreement among raft nodes before linearized reading' (duration: 142.633017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:30:46.776599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.41466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:30:46.776752Z","caller":"traceutil/trace.go:171","msg":"trace[1736891335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9744; }","duration":"143.597845ms","start":"2026-09-01T04:30:46.633146Z","end":"2026-09-01T04:30:46.776744Z","steps":["trace[1736891335] 'agreement among raft nodes before linearized reading' (duration: 143.42474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:30:46.777052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.895013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T04:30:46.77708Z","caller":"traceutil/trace.go:171","msg":"trace[219936768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9744; }","duration":"140.966345ms","start":"2026-09-01T04:30:46.636107Z","end":"2026-09-01T04:30:46.777074Z","steps":["trace[219936768] 'agreement among raft nodes before linearized reading' (duration: 140.854962ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:00.164596Z","caller":"traceutil/trace.go:171","msg":"trace[954300775] transaction","detail":"{read_only:false; response_revision:9818; number_of_response:1; }","duration":"158.826802ms","start":"2026-09-01T04:31:00.005727Z","end":"2026-09-01T04:31:00.164554Z","steps":["trace[954300775] 'process raft request' (duration: 158.345558ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:00.729503Z","caller":"traceutil/trace.go:171","msg":"trace[2110860724] transaction","detail":"{read_only:false; response_revision:9821; number_of_response:1; }","duration":"169.708907ms","start":"2026-09-01T04:31:00.559773Z","end":"2026-09-01T04:31:00.729482Z","steps":["trace[2110860724] 'process raft request' (duration: 169.652976ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:00.729568Z","caller":"traceutil/trace.go:171","msg":"trace[382477224] transaction","detail":"{read_only:false; response_revision:9820; number_of_response:1; }","duration":"185.472531ms","start":"2026-09-01T04:31:00.544075Z","end":"2026-09-01T04:31:00.729547Z","steps":["trace[382477224] 'process raft request' (duration: 93.273072ms)","trace[382477224] 'compare' (duration: 91.970712ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:31:00.729627Z","caller":"traceutil/trace.go:171","msg":"trace[885973610] transaction","detail":"{read_only:false; response_revision:9822; number_of_response:1; }","duration":"163.820367ms","start":"2026-09-01T04:31:00.565768Z","end":"2026-09-01T04:31:00.729589Z","steps":["trace[885973610] 'process raft request' (duration: 163.688133ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:32:34.467291Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8087} {"level":"info","ts":"2026-09-01T04:32:34.538802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8087,"took":"69.41234ms","hash":3458990703,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":28123136,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T04:32:34.538943Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3458990703,"revision":8087,"compact-revision":5926} {"level":"info","ts":"2026-09-01T04:36:38.9044Z","caller":"traceutil/trace.go:171","msg":"trace[1406976235] linearizableReadLoop","detail":"{readStateIndex:12679; appliedIndex:12678; }","duration":"270.513633ms","start":"2026-09-01T04:36:38.633868Z","end":"2026-09-01T04:36:38.904382Z","steps":["trace[1406976235] 'read index received' (duration: 270.353828ms)","trace[1406976235] 'applied index is now lower than readState.Index' (duration: 159.295µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:36:38.904469Z","caller":"traceutil/trace.go:171","msg":"trace[545749211] transaction","detail":"{read_only:false; response_revision:12251; number_of_response:1; }","duration":"299.386168ms","start":"2026-09-01T04:36:38.605053Z","end":"2026-09-01T04:36:38.904439Z","steps":["trace[545749211] 'process raft request' (duration: 299.212603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:36:38.904613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.855933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:36:38.9047Z","caller":"traceutil/trace.go:171","msg":"trace[993355506] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:12251; }","duration":"172.992427ms","start":"2026-09-01T04:36:38.731685Z","end":"2026-09-01T04:36:38.904677Z","steps":["trace[993355506] 'agreement among raft nodes before linearized reading' (duration: 172.838012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:36:38.904708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.264018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-01T04:36:38.904735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.084061ms","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-09-01T04:36:38.904765Z","caller":"traceutil/trace.go:171","msg":"trace[902722044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12251; }","duration":"163.341812ms","start":"2026-09-01T04:36:38.741402Z","end":"2026-09-01T04:36:38.904744Z","steps":["trace[902722044] 'agreement among raft nodes before linearized reading' (duration: 163.176416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:36:38.904773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.370877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-01T04:36:38.904774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.669248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:36:38.904814Z","caller":"traceutil/trace.go:171","msg":"trace[456572182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12251; }","duration":"191.162024ms","start":"2026-09-01T04:36:38.713631Z","end":"2026-09-01T04:36:38.904793Z","steps":["trace[456572182] 'agreement among raft nodes before linearized reading' (duration: 191.003199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:36:38.904635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.132835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T04:36:38.904852Z","caller":"traceutil/trace.go:171","msg":"trace[635129498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12251; }","duration":"202.483101ms","start":"2026-09-01T04:36:38.702347Z","end":"2026-09-01T04:36:38.90483Z","steps":["trace[635129498] 'agreement among raft nodes before linearized reading' (duration: 202.292364ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:36:38.904865Z","caller":"traceutil/trace.go:171","msg":"trace[66911592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12251; }","duration":"271.04178ms","start":"2026-09-01T04:36:38.633806Z","end":"2026-09-01T04:36:38.904847Z","steps":["trace[66911592] 'agreement among raft nodes before linearized reading' (duration: 270.654827ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:36:38.904885Z","caller":"traceutil/trace.go:171","msg":"trace[926064634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12251; }","duration":"212.435946ms","start":"2026-09-01T04:36:38.692435Z","end":"2026-09-01T04:36:38.904871Z","steps":["trace[926064634] 'agreement among raft nodes before linearized reading' (duration: 212.066354ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:36:39.029214Z","caller":"traceutil/trace.go:171","msg":"trace[1029144444] transaction","detail":"{read_only:false; response_revision:12253; number_of_response:1; }","duration":"118.331662ms","start":"2026-09-01T04:36:38.910854Z","end":"2026-09-01T04:36:39.029185Z","steps":["trace[1029144444] 'process raft request' (duration: 118.128546ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:36:39.029494Z","caller":"traceutil/trace.go:171","msg":"trace[938463493] transaction","detail":"{read_only:false; response_revision:12254; number_of_response:1; }","duration":"118.326464ms","start":"2026-09-01T04:36:38.911141Z","end":"2026-09-01T04:36:39.029467Z","steps":["trace[938463493] 'process raft request' (duration: 117.986853ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:36:39.029535Z","caller":"traceutil/trace.go:171","msg":"trace[1825590146] transaction","detail":"{read_only:false; response_revision:12255; number_of_response:1; }","duration":"118.257232ms","start":"2026-09-01T04:36:38.911246Z","end":"2026-09-01T04:36:39.029503Z","steps":["trace[1825590146] 'process raft request' (duration: 118.120778ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:37:34.473655Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10456} {"level":"info","ts":"2026-09-01T04:37:34.563883Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10456,"took":"88.419547ms","hash":83326893,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":26103808,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T04:37:34.563939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":83326893,"revision":10456,"compact-revision":8087} {"level":"info","ts":"2026-09-01T04:39:21.905432Z","caller":"traceutil/trace.go:171","msg":"trace[2130600016] transaction","detail":"{read_only:false; response_revision:13330; number_of_response:1; }","duration":"119.718535ms","start":"2026-09-01T04:39:21.785681Z","end":"2026-09-01T04:39:21.9054Z","steps":["trace[2130600016] 'process raft request' (duration: 119.487468ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:41:08.69078Z","caller":"traceutil/trace.go:171","msg":"trace[2072306434] transaction","detail":"{read_only:false; response_revision:14093; number_of_response:1; }","duration":"121.550832ms","start":"2026-09-01T04:41:08.569202Z","end":"2026-09-01T04:41:08.690753Z","steps":["trace[2072306434] 'process raft request' (duration: 112.480975ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:41:09.046771Z","caller":"traceutil/trace.go:171","msg":"trace[1793297967] transaction","detail":"{read_only:false; response_revision:14094; number_of_response:1; }","duration":"476.075067ms","start":"2026-09-01T04:41:08.570662Z","end":"2026-09-01T04:41:09.046737Z","steps":["trace[1793297967] 'process raft request' (duration: 443.47987ms)","trace[1793297967] 'compare' (duration: 32.35958ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:41:09.046992Z","caller":"traceutil/trace.go:171","msg":"trace[259755430] transaction","detail":"{read_only:false; response_revision:14095; number_of_response:1; }","duration":"475.978825ms","start":"2026-09-01T04:41:08.570985Z","end":"2026-09-01T04:41:09.046963Z","steps":["trace[259755430] 'process raft request' (duration: 475.692506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:41:09.047271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:41:08.570631Z","time spent":"476.542102ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:41:09.047291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:41:08.570951Z","time spent":"476.255813ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:41:09.052664Z","caller":"traceutil/trace.go:171","msg":"trace[490942914] transaction","detail":"{read_only:false; response_revision:14096; number_of_response:1; }","duration":"175.881796ms","start":"2026-09-01T04:41:08.876731Z","end":"2026-09-01T04:41:09.052613Z","steps":["trace[490942914] 'process raft request' (duration: 175.67172ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:42:34.481674Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12646} {"level":"info","ts":"2026-09-01T04:42:34.575528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12646,"took":"92.07734ms","hash":2007003439,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":28024832,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T04:42:34.575582Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2007003439,"revision":12646,"compact-revision":10456} {"level":"info","ts":"2026-09-01T04:43:05.654238Z","caller":"traceutil/trace.go:171","msg":"trace[1196307031] transaction","detail":"{read_only:false; response_revision:14840; number_of_response:1; }","duration":"118.094846ms","start":"2026-09-01T04:43:05.536118Z","end":"2026-09-01T04:43:05.654213Z","steps":["trace[1196307031] 'process raft request' (duration: 116.462346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:43:13.950772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.96899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T04:43:13.950864Z","caller":"traceutil/trace.go:171","msg":"trace[1853620257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14881; }","duration":"160.122525ms","start":"2026-09-01T04:43:13.790723Z","end":"2026-09-01T04:43:13.950846Z","steps":["trace[1853620257] 'range keys from in-memory index tree' (duration: 159.751553ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:43:13.950866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.414615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T04:43:13.950952Z","caller":"traceutil/trace.go:171","msg":"trace[627759972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14881; }","duration":"165.559489ms","start":"2026-09-01T04:43:13.785374Z","end":"2026-09-01T04:43:13.950934Z","steps":["trace[627759972] 'range keys from in-memory index tree' (duration: 165.115197ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:43:13.95088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.955465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:43:13.951091Z","caller":"traceutil/trace.go:171","msg":"trace[817923216] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:14881; }","duration":"128.180882ms","start":"2026-09-01T04:43:13.82287Z","end":"2026-09-01T04:43:13.951051Z","steps":["trace[817923216] 'count revisions from in-memory index tree' (duration: 127.893083ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:43:21.747901Z","caller":"traceutil/trace.go:171","msg":"trace[1155178051] transaction","detail":"{read_only:false; response_revision:14935; number_of_response:1; }","duration":"126.762469ms","start":"2026-09-01T04:43:21.621115Z","end":"2026-09-01T04:43:21.747878Z","steps":["trace[1155178051] 'process raft request' (duration: 126.664506ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:43:21.747925Z","caller":"traceutil/trace.go:171","msg":"trace[28717898] transaction","detail":"{read_only:false; response_revision:14934; number_of_response:1; }","duration":"161.871498ms","start":"2026-09-01T04:43:21.586018Z","end":"2026-09-01T04:43:21.74789Z","steps":["trace[28717898] 'process raft request' (duration: 121.738186ms)","trace[28717898] 'compare' (duration: 39.784852ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:43:21.748107Z","caller":"traceutil/trace.go:171","msg":"trace[311725402] linearizableReadLoop","detail":"{readStateIndex:15459; appliedIndex:15456; }","duration":"115.60632ms","start":"2026-09-01T04:43:21.632473Z","end":"2026-09-01T04:43:21.748079Z","steps":["trace[311725402] 'read index received' (duration: 75.255571ms)","trace[311725402] 'applied index is now lower than readState.Index' (duration: 40.349499ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:43:21.748209Z","caller":"traceutil/trace.go:171","msg":"trace[1331677915] transaction","detail":"{read_only:false; response_revision:14936; number_of_response:1; }","duration":"126.986867ms","start":"2026-09-01T04:43:21.621211Z","end":"2026-09-01T04:43:21.748198Z","steps":["trace[1331677915] 'process raft request' (duration: 126.629586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:43:21.748234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.742354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:43:21.748269Z","caller":"traceutil/trace.go:171","msg":"trace[706874034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14936; }","duration":"115.825066ms","start":"2026-09-01T04:43:21.632431Z","end":"2026-09-01T04:43:21.748256Z","steps":["trace[706874034] 'agreement among raft nodes before linearized reading' (duration: 115.750134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:43:22.719682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.469068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T04:43:22.71975Z","caller":"traceutil/trace.go:171","msg":"trace[1633747487] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:14940; }","duration":"438.607772ms","start":"2026-09-01T04:43:22.281123Z","end":"2026-09-01T04:43:22.719731Z","steps":["trace[1633747487] 'count revisions from in-memory index tree' (duration: 438.372296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:43:22.719779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:43:22.281093Z","time spent":"438.677204ms","remote":"127.0.0.1:59488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"info","ts":"2026-09-01T04:43:45.96371Z","caller":"traceutil/trace.go:171","msg":"trace[1631936078] transaction","detail":"{read_only:false; response_revision:15253; number_of_response:1; }","duration":"370.536441ms","start":"2026-09-01T04:43:45.59314Z","end":"2026-09-01T04:43:45.963677Z","steps":["trace[1631936078] 'process raft request' (duration: 370.341675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:43:45.96397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:43:45.593111Z","time spent":"370.689126ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:43:46.052985Z","caller":"traceutil/trace.go:171","msg":"trace[1221880154] transaction","detail":"{read_only:false; response_revision:15254; number_of_response:1; }","duration":"459.802419ms","start":"2026-09-01T04:43:45.593139Z","end":"2026-09-01T04:43:46.052942Z","steps":["trace[1221880154] 'process raft request' (duration: 457.644943ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:43:46.053178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:43:45.593094Z","time spent":"459.987424ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:43:46.05331Z","caller":"traceutil/trace.go:171","msg":"trace[1941657919] transaction","detail":"{read_only:false; response_revision:15255; number_of_response:1; }","duration":"459.474268ms","start":"2026-09-01T04:43:45.593813Z","end":"2026-09-01T04:43:46.053288Z","steps":["trace[1941657919] 'process raft request' (duration: 459.087147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:43:46.053404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:43:45.593798Z","time spent":"459.56099ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:43:46.053489Z","caller":"traceutil/trace.go:171","msg":"trace[892366265] transaction","detail":"{read_only:false; response_revision:15257; number_of_response:1; }","duration":"190.827869ms","start":"2026-09-01T04:43:45.862635Z","end":"2026-09-01T04:43:46.053463Z","steps":["trace[892366265] 'process raft request' (duration: 190.736857ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:43:46.053588Z","caller":"traceutil/trace.go:171","msg":"trace[1657003402] transaction","detail":"{read_only:false; response_revision:15258; number_of_response:1; }","duration":"188.070424ms","start":"2026-09-01T04:43:45.865502Z","end":"2026-09-01T04:43:46.053573Z","steps":["trace[1657003402] 'process raft request' (duration: 187.92259ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:43:46.053511Z","caller":"traceutil/trace.go:171","msg":"trace[1996171431] transaction","detail":"{read_only:false; response_revision:15256; number_of_response:1; }","duration":"456.499557ms","start":"2026-09-01T04:43:45.596997Z","end":"2026-09-01T04:43:46.053497Z","steps":["trace[1996171431] 'process raft request' (duration: 456.158607ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:43:46.053726Z","caller":"traceutil/trace.go:171","msg":"trace[770949876] transaction","detail":"{read_only:false; response_revision:15259; number_of_response:1; }","duration":"182.347251ms","start":"2026-09-01T04:43:45.871369Z","end":"2026-09-01T04:43:46.053716Z","steps":["trace[770949876] 'process raft request' (duration: 182.150885ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:43:46.053799Z","caller":"traceutil/trace.go:171","msg":"trace[1691411075] transaction","detail":"{read_only:false; response_revision:15260; number_of_response:1; }","duration":"181.98794ms","start":"2026-09-01T04:43:45.871803Z","end":"2026-09-01T04:43:46.053791Z","steps":["trace[1691411075] 'process raft request' (duration: 181.810585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:43:46.053837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.317474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-01T04:43:46.05387Z","caller":"traceutil/trace.go:171","msg":"trace[1845365860] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15260; }","duration":"146.391166ms","start":"2026-09-01T04:43:45.907469Z","end":"2026-09-01T04:43:46.05386Z","steps":["trace[1845365860] 'agreement among raft nodes before linearized reading' (duration: 146.257362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:43:46.053889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:43:45.596972Z","time spent":"456.784846ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:43:46.053707Z","caller":"traceutil/trace.go:171","msg":"trace[513422360] linearizableReadLoop","detail":"{readStateIndex:15794; appliedIndex:15786; }","duration":"146.173819ms","start":"2026-09-01T04:43:45.907523Z","end":"2026-09-01T04:43:46.053697Z","steps":["trace[513422360] 'read index received' (duration: 55.950593ms)","trace[513422360] 'applied index is now lower than readState.Index' (duration: 90.222456ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:44:22.380267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.396447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:44:22.380387Z","caller":"traceutil/trace.go:171","msg":"trace[1888645664] transaction","detail":"{read_only:false; response_revision:15493; number_of_response:1; }","duration":"182.126025ms","start":"2026-09-01T04:44:22.198241Z","end":"2026-09-01T04:44:22.380368Z","steps":["trace[1888645664] 'process raft request' (duration: 57.533732ms)","trace[1888645664] 'compare' (duration: 124.266503ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:44:22.387115Z","caller":"traceutil/trace.go:171","msg":"trace[1347161219] transaction","detail":"{read_only:false; response_revision:15495; number_of_response:1; }","duration":"177.182685ms","start":"2026-09-01T04:44:22.209904Z","end":"2026-09-01T04:44:22.387087Z","steps":["trace[1347161219] 'process raft request' (duration: 177.083152ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:44:22.387198Z","caller":"traceutil/trace.go:171","msg":"trace[2096944962] transaction","detail":"{read_only:false; response_revision:15494; number_of_response:1; }","duration":"178.037089ms","start":"2026-09-01T04:44:22.209109Z","end":"2026-09-01T04:44:22.387146Z","steps":["trace[2096944962] 'process raft request' (duration: 177.743771ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:44:22.387322Z","caller":"traceutil/trace.go:171","msg":"trace[1556228215] transaction","detail":"{read_only:false; response_revision:15496; number_of_response:1; }","duration":"176.717189ms","start":"2026-09-01T04:44:22.210592Z","end":"2026-09-01T04:44:22.387309Z","steps":["trace[1556228215] 'process raft request' (duration: 176.429231ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:44:22.387369Z","caller":"traceutil/trace.go:171","msg":"trace[1541432011] linearizableReadLoop","detail":"{readStateIndex:16038; appliedIndex:16034; }","duration":"161.035712ms","start":"2026-09-01T04:44:22.226244Z","end":"2026-09-01T04:44:22.387279Z","steps":["trace[1541432011] 'read index received' (duration: 29.624092ms)","trace[1541432011] 'applied index is now lower than readState.Index' (duration: 131.41003ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:44:22.387561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.30691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-01T04:44:22.387596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.639748ms","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-09-01T04:44:22.387629Z","caller":"traceutil/trace.go:171","msg":"trace[125722877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15496; }","duration":"155.69753ms","start":"2026-09-01T04:44:22.23192Z","end":"2026-09-01T04:44:22.387618Z","steps":["trace[125722877] 'agreement among raft nodes before linearized reading' (duration: 155.558946ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:44:22.38763Z","caller":"traceutil/trace.go:171","msg":"trace[442773412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15496; }","duration":"161.417544ms","start":"2026-09-01T04:44:22.226197Z","end":"2026-09-01T04:44:22.387614Z","steps":["trace[442773412] 'agreement among raft nodes before linearized reading' (duration: 161.223869ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:44:22.564144Z","caller":"traceutil/trace.go:171","msg":"trace[1768259129] linearizableReadLoop","detail":"{readStateIndex:16040; appliedIndex:16038; }","duration":"139.664322ms","start":"2026-09-01T04:44:22.424455Z","end":"2026-09-01T04:44:22.564119Z","steps":["trace[1768259129] 'read index received' (duration: 125.837262ms)","trace[1768259129] 'applied index is now lower than readState.Index' (duration: 13.826169ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:44:22.564204Z","caller":"traceutil/trace.go:171","msg":"trace[1968574756] transaction","detail":"{read_only:false; response_revision:15497; number_of_response:1; }","duration":"168.941222ms","start":"2026-09-01T04:44:22.395234Z","end":"2026-09-01T04:44:22.564175Z","steps":["trace[1968574756] 'process raft request' (duration: 155.05441ms)","trace[1968574756] 'compare' (duration: 13.653945ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:44:22.564299Z","caller":"traceutil/trace.go:171","msg":"trace[917232509] transaction","detail":"{read_only:false; response_revision:15498; number_of_response:1; }","duration":"163.401905ms","start":"2026-09-01T04:44:22.400887Z","end":"2026-09-01T04:44:22.564289Z","steps":["trace[917232509] 'process raft request' (duration: 163.190937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:44:22.564503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.077532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T04:44:22.564571Z","caller":"traceutil/trace.go:171","msg":"trace[2128834485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15498; }","duration":"134.197875ms","start":"2026-09-01T04:44:22.430354Z","end":"2026-09-01T04:44:22.564552Z","steps":["trace[2128834485] 'agreement among raft nodes before linearized reading' (duration: 134.01097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:44:22.564765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.320091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T04:44:22.564799Z","caller":"traceutil/trace.go:171","msg":"trace[2068260795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15498; }","duration":"140.402004ms","start":"2026-09-01T04:44:22.424388Z","end":"2026-09-01T04:44:22.56479Z","steps":["trace[2068260795] 'agreement among raft nodes before linearized reading' (duration: 140.310132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:44:22.565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.752115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4sw4v\" ","response":"range_response_count:1 size:20557"} {"level":"info","ts":"2026-09-01T04:44:22.565074Z","caller":"traceutil/trace.go:171","msg":"trace[233926260] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-4sw4v; range_end:; response_count:1; response_revision:15498; }","duration":"117.895288ms","start":"2026-09-01T04:44:22.447164Z","end":"2026-09-01T04:44:22.565059Z","steps":["trace[233926260] 'agreement among raft nodes before linearized reading' (duration: 117.62743ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:45:23.080814Z","caller":"traceutil/trace.go:171","msg":"trace[1925422953] transaction","detail":"{read_only:false; response_revision:16124; number_of_response:1; }","duration":"194.45144ms","start":"2026-09-01T04:45:22.886334Z","end":"2026-09-01T04:45:23.080785Z","steps":["trace[1925422953] 'process raft request' (duration: 194.197912ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:45:23.149587Z","caller":"traceutil/trace.go:171","msg":"trace[1348735201] transaction","detail":"{read_only:false; response_revision:16125; number_of_response:1; }","duration":"263.033188ms","start":"2026-09-01T04:45:22.886532Z","end":"2026-09-01T04:45:23.149565Z","steps":["trace[1348735201] 'process raft request' (duration: 262.902664ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:45:23.168839Z","caller":"traceutil/trace.go:171","msg":"trace[560349958] linearizableReadLoop","detail":"{readStateIndex:16684; appliedIndex:16682; }","duration":"268.094343ms","start":"2026-09-01T04:45:22.900715Z","end":"2026-09-01T04:45:23.16881Z","steps":["trace[560349958] 'read index received' (duration: 179.951809ms)","trace[560349958] 'applied index is now lower than readState.Index' (duration: 88.141084ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:45:23.169056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.324049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T04:45:23.169102Z","caller":"traceutil/trace.go:171","msg":"trace[1366607141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16125; }","duration":"268.429072ms","start":"2026-09-01T04:45:22.900664Z","end":"2026-09-01T04:45:23.169093Z","steps":["trace[1366607141] 'agreement among raft nodes before linearized reading' (duration: 268.218026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:45:23.16914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.743591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T04:45:23.169187Z","caller":"traceutil/trace.go:171","msg":"trace[1874754179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16125; }","duration":"265.856053ms","start":"2026-09-01T04:45:22.903316Z","end":"2026-09-01T04:45:23.169172Z","steps":["trace[1874754179] 'agreement among raft nodes before linearized reading' (duration: 265.667128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:45:23.169687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.624323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:45:23.169727Z","caller":"traceutil/trace.go:171","msg":"trace[126959202] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16125; }","duration":"167.698195ms","start":"2026-09-01T04:45:23.002019Z","end":"2026-09-01T04:45:23.169717Z","steps":["trace[126959202] 'agreement among raft nodes before linearized reading' (duration: 167.632903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:45:23.170235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.625392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-01T04:45:23.170272Z","caller":"traceutil/trace.go:171","msg":"trace[1469179782] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16125; }","duration":"165.703754ms","start":"2026-09-01T04:45:23.004559Z","end":"2026-09-01T04:45:23.170263Z","steps":["trace[1469179782] 'agreement among raft nodes before linearized reading' (duration: 164.870998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:45:23.170282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.249972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:45:23.17038Z","caller":"traceutil/trace.go:171","msg":"trace[1437699966] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16125; }","duration":"168.397297ms","start":"2026-09-01T04:45:23.001964Z","end":"2026-09-01T04:45:23.170361Z","steps":["trace[1437699966] 'agreement among raft nodes before linearized reading' (duration: 168.216542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:45:23.170562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.972792ms","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-09-01T04:45:23.170612Z","caller":"traceutil/trace.go:171","msg":"trace[335984076] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16125; }","duration":"166.069495ms","start":"2026-09-01T04:45:23.004531Z","end":"2026-09-01T04:45:23.170601Z","steps":["trace[335984076] 'agreement among raft nodes before linearized reading' (duration: 165.995973ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:47:34.488897Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14645} {"level":"info","ts":"2026-09-01T04:47:34.577831Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14645,"took":"81.80031ms","hash":2787559672,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":31051776,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T04:47:34.577919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2787559672,"revision":14645,"compact-revision":12646} {"level":"info","ts":"2026-09-01T04:47:38.739183Z","caller":"traceutil/trace.go:171","msg":"trace[695325467] transaction","detail":"{read_only:false; response_revision:17056; number_of_response:1; }","duration":"122.326903ms","start":"2026-09-01T04:47:38.616837Z","end":"2026-09-01T04:47:38.739164Z","steps":["trace[695325467] 'process raft request' (duration: 122.195659ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:47:59.794708Z","caller":"traceutil/trace.go:171","msg":"trace[122528810] linearizableReadLoop","detail":"{readStateIndex:17859; appliedIndex:17858; }","duration":"161.173767ms","start":"2026-09-01T04:47:59.633508Z","end":"2026-09-01T04:47:59.794682Z","steps":["trace[122528810] 'read index received' (duration: 151.858053ms)","trace[122528810] 'applied index is now lower than readState.Index' (duration: 9.314634ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:47:59.794895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.365973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T04:47:59.794876Z","caller":"traceutil/trace.go:171","msg":"trace[172887168] transaction","detail":"{read_only:false; response_revision:17260; number_of_response:1; }","duration":"162.188618ms","start":"2026-09-01T04:47:59.632646Z","end":"2026-09-01T04:47:59.794834Z","steps":["trace[172887168] 'process raft request' (duration: 152.796312ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:47:59.794953Z","caller":"traceutil/trace.go:171","msg":"trace[1975297062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17260; }","duration":"161.456936ms","start":"2026-09-01T04:47:59.633481Z","end":"2026-09-01T04:47:59.794938Z","steps":["trace[1975297062] 'agreement among raft nodes before linearized reading' (duration: 161.357983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:47:59.795441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.908579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-neutron-server\" ","response":"range_response_count:1 size:9623"} {"level":"info","ts":"2026-09-01T04:47:59.795775Z","caller":"traceutil/trace.go:171","msg":"trace[254039190] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-neutron-server; range_end:; response_count:1; response_revision:17260; }","duration":"161.27312ms","start":"2026-09-01T04:47:59.63448Z","end":"2026-09-01T04:47:59.795753Z","steps":["trace[254039190] 'agreement among raft nodes before linearized reading' (duration: 160.779195ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:48:27.801338Z","caller":"traceutil/trace.go:171","msg":"trace[1902112817] linearizableReadLoop","detail":"{readStateIndex:18207; appliedIndex:18204; }","duration":"167.575322ms","start":"2026-09-01T04:48:27.633737Z","end":"2026-09-01T04:48:27.801312Z","steps":["trace[1902112817] 'read index received' (duration: 401.483µs)","trace[1902112817] 'applied index is now lower than readState.Index' (duration: 167.172869ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:48:27.801484Z","caller":"traceutil/trace.go:171","msg":"trace[680746848] transaction","detail":"{read_only:false; response_revision:17600; number_of_response:1; }","duration":"244.475692ms","start":"2026-09-01T04:48:27.556994Z","end":"2026-09-01T04:48:27.801469Z","steps":["trace[680746848] 'process raft request' (duration: 178.06072ms)","trace[680746848] 'compare' (duration: 66.01656ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:48:27.801647Z","caller":"traceutil/trace.go:171","msg":"trace[610063706] transaction","detail":"{read_only:false; response_revision:17602; number_of_response:1; }","duration":"229.451876ms","start":"2026-09-01T04:48:27.572118Z","end":"2026-09-01T04:48:27.80157Z","steps":["trace[610063706] 'process raft request' (duration: 229.146307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:27.801873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.12901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T04:48:27.801928Z","caller":"traceutil/trace.go:171","msg":"trace[734245515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17602; }","duration":"168.239623ms","start":"2026-09-01T04:48:27.633674Z","end":"2026-09-01T04:48:27.801914Z","steps":["trace[734245515] 'agreement among raft nodes before linearized reading' (duration: 168.124558ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:48:27.801974Z","caller":"traceutil/trace.go:171","msg":"trace[1800391786] transaction","detail":"{read_only:false; response_revision:17601; number_of_response:1; }","duration":"238.954275ms","start":"2026-09-01T04:48:27.563005Z","end":"2026-09-01T04:48:27.801959Z","steps":["trace[1800391786] 'process raft request' (duration: 238.214212ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:27.802204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.985626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.4\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-01T04:48:27.802258Z","caller":"traceutil/trace.go:171","msg":"trace[840891574] range","detail":"{range_begin:/registry/masterleases/162.253.55.4; range_end:; response_count:1; response_revision:17602; }","duration":"105.076709ms","start":"2026-09-01T04:48:27.697168Z","end":"2026-09-01T04:48:27.802245Z","steps":["trace[840891574] 'agreement among raft nodes before linearized reading' (duration: 104.924414ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:28.090811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.99956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T04:48:28.090952Z","caller":"traceutil/trace.go:171","msg":"trace[2020636246] transaction","detail":"{read_only:false; response_revision:17603; number_of_response:1; }","duration":"245.03679ms","start":"2026-09-01T04:48:27.845889Z","end":"2026-09-01T04:48:28.090926Z","steps":["trace[2020636246] 'process raft request' (duration: 87.833504ms)","trace[2020636246] 'compare' (duration: 156.710221ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:48:28.14212Z","caller":"traceutil/trace.go:171","msg":"trace[245403566] linearizableReadLoop","detail":"{readStateIndex:18210; appliedIndex:18208; }","duration":"265.225555ms","start":"2026-09-01T04:48:27.876866Z","end":"2026-09-01T04:48:28.142091Z","steps":["trace[245403566] 'read index received' (duration: 56.875462ms)","trace[245403566] 'applied index is now lower than readState.Index' (duration: 208.348893ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:48:28.142302Z","caller":"traceutil/trace.go:171","msg":"trace[1062005729] transaction","detail":"{read_only:false; response_revision:17604; number_of_response:1; }","duration":"294.468235ms","start":"2026-09-01T04:48:27.847813Z","end":"2026-09-01T04:48:28.142281Z","steps":["trace[1062005729] 'process raft request' (duration: 294.120464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:28.142319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.428051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T04:48:28.142403Z","caller":"traceutil/trace.go:171","msg":"trace[1048599260] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17604; }","duration":"265.554735ms","start":"2026-09-01T04:48:27.876822Z","end":"2026-09-01T04:48:28.142377Z","steps":["trace[1048599260] 'agreement among raft nodes before linearized reading' (duration: 265.414391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:28.142517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.258726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-09-01T04:48:28.142536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.010553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-hxmqz\" ","response":"range_response_count:1 size:18560"} {"level":"info","ts":"2026-09-01T04:48:28.142589Z","caller":"traceutil/trace.go:171","msg":"trace[780332464] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17604; }","duration":"265.351388ms","start":"2026-09-01T04:48:27.877217Z","end":"2026-09-01T04:48:28.142569Z","steps":["trace[780332464] 'agreement among raft nodes before linearized reading' (duration: 265.147332ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:48:28.142599Z","caller":"traceutil/trace.go:171","msg":"trace[936269279] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-hxmqz; range_end:; response_count:1; response_revision:17604; }","duration":"163.118296ms","start":"2026-09-01T04:48:27.979464Z","end":"2026-09-01T04:48:28.142583Z","steps":["trace[936269279] 'agreement among raft nodes before linearized reading' (duration: 162.93446ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:28.142796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.398658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-09-01T04:48:28.142864Z","caller":"traceutil/trace.go:171","msg":"trace[665945507] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17604; }","duration":"260.48058ms","start":"2026-09-01T04:48:27.882369Z","end":"2026-09-01T04:48:28.14285Z","steps":["trace[665945507] 'agreement among raft nodes before linearized reading' (duration: 260.319835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:28.14291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.549522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T04:48:28.142959Z","caller":"traceutil/trace.go:171","msg":"trace[1311103915] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17604; }","duration":"260.618274ms","start":"2026-09-01T04:48:27.882328Z","end":"2026-09-01T04:48:28.142946Z","steps":["trace[1311103915] 'agreement among raft nodes before linearized reading' (duration: 260.533501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:28.142959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.431363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-dqhzz\" ","response":"range_response_count:1 size:14191"} {"level":"info","ts":"2026-09-01T04:48:28.143009Z","caller":"traceutil/trace.go:171","msg":"trace[1517317990] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-dqhzz; range_end:; response_count:1; response_revision:17604; }","duration":"181.546996ms","start":"2026-09-01T04:48:27.961445Z","end":"2026-09-01T04:48:28.142992Z","steps":["trace[1517317990] 'agreement among raft nodes before linearized reading' (duration: 181.405392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:28.143099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.586612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-trnzb\" ","response":"range_response_count:1 size:18585"} {"level":"info","ts":"2026-09-01T04:48:28.143152Z","caller":"traceutil/trace.go:171","msg":"trace[1018464588] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-trnzb; range_end:; response_count:1; response_revision:17604; }","duration":"101.676715ms","start":"2026-09-01T04:48:28.04146Z","end":"2026-09-01T04:48:28.143137Z","steps":["trace[1018464588] 'agreement among raft nodes before linearized reading' (duration: 101.543181ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:28.143227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.298874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T04:48:28.143325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.755643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T04:48:28.143351Z","caller":"traceutil/trace.go:171","msg":"trace[632771541] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17604; }","duration":"158.796614ms","start":"2026-09-01T04:48:27.984546Z","end":"2026-09-01T04:48:28.143343Z","steps":["trace[632771541] 'agreement among raft nodes before linearized reading' (duration: 158.761183ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:48:28.143341Z","caller":"traceutil/trace.go:171","msg":"trace[1310210482] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17604; }","duration":"261.444319ms","start":"2026-09-01T04:48:27.881876Z","end":"2026-09-01T04:48:28.143321Z","steps":["trace[1310210482] 'agreement among raft nodes before linearized reading' (duration: 261.239453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:28.143301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.535964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rskt8\" ","response":"range_response_count:1 size:6444"} {"level":"info","ts":"2026-09-01T04:48:28.143449Z","caller":"traceutil/trace.go:171","msg":"trace[408996249] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rskt8; range_end:; response_count:1; response_revision:17604; }","duration":"244.703419ms","start":"2026-09-01T04:48:27.898734Z","end":"2026-09-01T04:48:28.143437Z","steps":["trace[408996249] 'agreement among raft nodes before linearized reading' (duration: 244.38949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:28.143549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.765715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-hvdcv\" ","response":"range_response_count:1 size:9708"} {"level":"warn","ts":"2026-09-01T04:48:28.143288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.30769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-09-01T04:48:28.143583Z","caller":"traceutil/trace.go:171","msg":"trace[1534687859] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-hvdcv; range_end:; response_count:1; response_revision:17604; }","duration":"161.816746ms","start":"2026-09-01T04:48:27.981758Z","end":"2026-09-01T04:48:28.143575Z","steps":["trace[1534687859] 'agreement among raft nodes before linearized reading' (duration: 161.725824ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:48:28.143632Z","caller":"traceutil/trace.go:171","msg":"trace[1547269574] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17604; }","duration":"158.644721ms","start":"2026-09-01T04:48:27.984952Z","end":"2026-09-01T04:48:28.143597Z","steps":["trace[1547269574] 'agreement among raft nodes before linearized reading' (duration: 158.274439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:28.143446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.465139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-09-01T04:48:28.143793Z","caller":"traceutil/trace.go:171","msg":"trace[1523962383] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17604; }","duration":"261.825911ms","start":"2026-09-01T04:48:27.881954Z","end":"2026-09-01T04:48:28.14378Z","steps":["trace[1523962383] 'agreement among raft nodes before linearized reading' (duration: 261.414928ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:51:37.886695Z","caller":"traceutil/trace.go:171","msg":"trace[1703625039] linearizableReadLoop","detail":"{readStateIndex:19483; appliedIndex:19482; }","duration":"176.158993ms","start":"2026-09-01T04:51:37.710515Z","end":"2026-09-01T04:51:37.886674Z","steps":["trace[1703625039] 'read index received' (duration: 176.032849ms)","trace[1703625039] 'applied index is now lower than readState.Index' (duration: 125.533µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:51:37.887047Z","caller":"traceutil/trace.go:171","msg":"trace[537965218] transaction","detail":"{read_only:false; response_revision:18836; number_of_response:1; }","duration":"198.588226ms","start":"2026-09-01T04:51:37.688447Z","end":"2026-09-01T04:51:37.887035Z","steps":["trace[537965218] 'process raft request' (duration: 198.02775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:51:37.887301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.771952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.4\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-01T04:51:37.887345Z","caller":"traceutil/trace.go:171","msg":"trace[1238878041] range","detail":"{range_begin:/registry/masterleases/162.253.55.4; range_end:; response_count:1; response_revision:18836; }","duration":"176.828283ms","start":"2026-09-01T04:51:37.710509Z","end":"2026-09-01T04:51:37.887337Z","steps":["trace[1238878041] 'agreement among raft nodes before linearized reading' (duration: 176.70694ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:51:46.011453Z","caller":"traceutil/trace.go:171","msg":"trace[278200576] transaction","detail":"{read_only:false; response_revision:18883; number_of_response:1; }","duration":"188.327304ms","start":"2026-09-01T04:51:45.823092Z","end":"2026-09-01T04:51:46.011419Z","steps":["trace[278200576] 'process raft request' (duration: 188.056945ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:51:46.670934Z","caller":"traceutil/trace.go:171","msg":"trace[1581219177] transaction","detail":"{read_only:false; response_revision:18887; number_of_response:1; }","duration":"201.899196ms","start":"2026-09-01T04:51:46.469007Z","end":"2026-09-01T04:51:46.670906Z","steps":["trace[1581219177] 'process raft request' (duration: 185.58074ms)","trace[1581219177] 'compare' (duration: 14.823601ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:51:47.027432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.312093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-01T04:51:47.027555Z","caller":"traceutil/trace.go:171","msg":"trace[1371985414] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18888; }","duration":"255.458906ms","start":"2026-09-01T04:51:46.772055Z","end":"2026-09-01T04:51:47.027514Z","steps":["trace[1371985414] 'range keys from in-memory index tree' (duration: 255.122927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:51:56.293497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.100551ms","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-09-01T04:51:56.293595Z","caller":"traceutil/trace.go:171","msg":"trace[805290138] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:19038; }","duration":"108.197863ms","start":"2026-09-01T04:51:56.185361Z","end":"2026-09-01T04:51:56.293559Z","steps":["trace[805290138] 'agreement among raft nodes before linearized reading' (duration: 108.016738ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:51:56.293991Z","caller":"traceutil/trace.go:171","msg":"trace[6411960] transaction","detail":"{read_only:false; response_revision:19037; number_of_response:1; }","duration":"104.740219ms","start":"2026-09-01T04:51:56.189235Z","end":"2026-09-01T04:51:56.293975Z","steps":["trace[6411960] 'process raft request' (duration: 104.034977ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:51:56.293994Z","caller":"traceutil/trace.go:171","msg":"trace[855092828] transaction","detail":"{read_only:false; response_revision:19036; number_of_response:1; }","duration":"108.942716ms","start":"2026-09-01T04:51:56.185014Z","end":"2026-09-01T04:51:56.293957Z","steps":["trace[855092828] 'process raft request' (duration: 72.711223ms)","trace[855092828] 'compare' (duration: 35.411298ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:51:56.473105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.085774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-09-01T04:51:56.473182Z","caller":"traceutil/trace.go:171","msg":"trace[1025597581] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:19048; }","duration":"136.202557ms","start":"2026-09-01T04:51:56.336961Z","end":"2026-09-01T04:51:56.473163Z","steps":["trace[1025597581] 'agreement among raft nodes before linearized reading' (duration: 135.960059ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:52:29.729823Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":20005,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T04:52:29.743204Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20005} {"level":"info","ts":"2026-09-01T04:52:29.743394Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15005} {"level":"info","ts":"2026-09-01T04:52:34.496083Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17024} {"level":"info","ts":"2026-09-01T04:52:34.597391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17024,"took":"97.759736ms","hash":1245034411,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":33103872,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-01T04:52:34.597471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1245034411,"revision":17024,"compact-revision":14645} {"level":"info","ts":"2026-09-01T04:57:13.926614Z","caller":"traceutil/trace.go:171","msg":"trace[1136431551] transaction","detail":"{read_only:false; response_revision:21481; number_of_response:1; }","duration":"116.044643ms","start":"2026-09-01T04:57:13.810545Z","end":"2026-09-01T04:57:13.926589Z","steps":["trace[1136431551] 'process raft request' (duration: 115.843337ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:57:34.50471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19469} {"level":"info","ts":"2026-09-01T04:57:34.629722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19469,"took":"118.633732ms","hash":3366168755,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":29970432,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T04:57:34.629786Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3366168755,"revision":19469,"compact-revision":17024} {"level":"warn","ts":"2026-09-01T04:57:51.685976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.920137ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T04:57:51.686148Z","caller":"traceutil/trace.go:171","msg":"trace[702340174] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:21674; }","duration":"181.124914ms","start":"2026-09-01T04:57:51.504999Z","end":"2026-09-01T04:57:51.686124Z","steps":["trace[702340174] 'range keys from in-memory index tree' (duration: 180.893847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:57:51.686456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.366484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T04:57:51.68659Z","caller":"traceutil/trace.go:171","msg":"trace[1889726845] transaction","detail":"{read_only:false; response_revision:21675; number_of_response:1; }","duration":"254.24978ms","start":"2026-09-01T04:57:51.432319Z","end":"2026-09-01T04:57:51.686568Z","steps":["trace[1889726845] 'process raft request' (duration: 132.674279ms)","trace[1889726845] 'compare' (duration: 121.254631ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:57:51.711761Z","caller":"traceutil/trace.go:171","msg":"trace[388646993] transaction","detail":"{read_only:false; response_revision:21676; number_of_response:1; }","duration":"277.329783ms","start":"2026-09-01T04:57:51.434406Z","end":"2026-09-01T04:57:51.711736Z","steps":["trace[388646993] 'process raft request' (duration: 276.640032ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:57:51.860084Z","caller":"traceutil/trace.go:171","msg":"trace[994801616] transaction","detail":"{read_only:false; response_revision:21677; number_of_response:1; }","duration":"103.209122ms","start":"2026-09-01T04:57:51.756832Z","end":"2026-09-01T04:57:51.860041Z","steps":["trace[994801616] 'process raft request' (duration: 84.733919ms)","trace[994801616] 'compare' (duration: 17.787852ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:58:08.024712Z","caller":"traceutil/trace.go:171","msg":"trace[804801993] transaction","detail":"{read_only:false; response_revision:21758; number_of_response:1; }","duration":"180.496565ms","start":"2026-09-01T04:58:07.844153Z","end":"2026-09-01T04:58:08.024649Z","steps":["trace[804801993] 'process raft request' (duration: 147.286504ms)","trace[804801993] 'compare' (duration: 32.857931ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:58:08.297398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.203773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T04:58:08.297665Z","caller":"traceutil/trace.go:171","msg":"trace[462164765] linearizableReadLoop","detail":"{readStateIndex:22509; appliedIndex:22505; }","duration":"270.838535ms","start":"2026-09-01T04:58:08.026811Z","end":"2026-09-01T04:58:08.297649Z","steps":["trace[462164765] 'read index received' (duration: 143.21584ms)","trace[462164765] 'applied index is now lower than readState.Index' (duration: 127.621585ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:58:08.297909Z","caller":"traceutil/trace.go:171","msg":"trace[1408980708] transaction","detail":"{read_only:false; response_revision:21759; number_of_response:1; }","duration":"450.872505ms","start":"2026-09-01T04:58:07.847023Z","end":"2026-09-01T04:58:08.297895Z","steps":["trace[1408980708] 'process raft request' (duration: 323.25185ms)","trace[1408980708] 'compare' (duration: 126.658917ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:58:08.297981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:58:07.847Z","time spent":"450.944808ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:58:08.298161Z","caller":"traceutil/trace.go:171","msg":"trace[59313444] transaction","detail":"{read_only:false; response_revision:21760; number_of_response:1; }","duration":"450.997991ms","start":"2026-09-01T04:58:07.847154Z","end":"2026-09-01T04:58:08.298152Z","steps":["trace[59313444] 'process raft request' (duration: 450.33568ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:58:08.298219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:58:07.847135Z","time spent":"451.055702ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:58:08.298346Z","caller":"traceutil/trace.go:171","msg":"trace[390997568] transaction","detail":"{read_only:false; response_revision:21761; number_of_response:1; }","duration":"383.601769ms","start":"2026-09-01T04:58:07.914736Z","end":"2026-09-01T04:58:08.298338Z","steps":["trace[390997568] 'process raft request' (duration: 382.831875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:58:08.298401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:58:07.914708Z","time spent":"383.666951ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:58:08.29855Z","caller":"traceutil/trace.go:171","msg":"trace[544983924] transaction","detail":"{read_only:false; response_revision:21762; number_of_response:1; }","duration":"377.315148ms","start":"2026-09-01T04:58:07.921226Z","end":"2026-09-01T04:58:08.298541Z","steps":["trace[544983924] 'process raft request' (duration: 376.388189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:58:08.298605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:58:07.921197Z","time spent":"377.379269ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:58:08.298798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.98494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-09-01T04:58:08.298834Z","caller":"traceutil/trace.go:171","msg":"trace[383892661] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:21762; }","duration":"272.058212ms","start":"2026-09-01T04:58:08.026767Z","end":"2026-09-01T04:58:08.298825Z","steps":["trace[383892661] 'agreement among raft nodes before linearized reading' (duration: 271.945389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:58:08.298987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.443249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T04:58:08.299022Z","caller":"traceutil/trace.go:171","msg":"trace[406976541] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:21762; }","duration":"216.528063ms","start":"2026-09-01T04:58:08.082484Z","end":"2026-09-01T04:58:08.299012Z","steps":["trace[406976541] 'agreement among raft nodes before linearized reading' (duration: 216.476671ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:58:08.587726Z","caller":"traceutil/trace.go:171","msg":"trace[907301588] transaction","detail":"{read_only:false; response_revision:21764; number_of_response:1; }","duration":"258.145389ms","start":"2026-09-01T04:58:08.329548Z","end":"2026-09-01T04:58:08.587694Z","steps":["trace[907301588] 'process raft request' (duration: 258.053166ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:58:08.58777Z","caller":"traceutil/trace.go:171","msg":"trace[1526229658] transaction","detail":"{read_only:false; response_revision:21763; number_of_response:1; }","duration":"263.014198ms","start":"2026-09-01T04:58:08.324711Z","end":"2026-09-01T04:58:08.587725Z","steps":["trace[1526229658] 'process raft request' (duration: 177.417112ms)","trace[1526229658] 'compare' (duration: 85.150322ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:58:09.119904Z","caller":"traceutil/trace.go:171","msg":"trace[2088845653] transaction","detail":"{read_only:false; response_revision:21765; number_of_response:1; }","duration":"256.170469ms","start":"2026-09-01T04:58:08.86371Z","end":"2026-09-01T04:58:09.119881Z","steps":["trace[2088845653] 'process raft request' (duration: 256.016384ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:59:25.021691Z","caller":"traceutil/trace.go:171","msg":"trace[1847810693] transaction","detail":"{read_only:false; response_revision:22445; number_of_response:1; }","duration":"114.026151ms","start":"2026-09-01T04:59:24.90764Z","end":"2026-09-01T04:59:25.021666Z","steps":["trace[1847810693] 'process raft request' (duration: 113.873557ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:59:25.030912Z","caller":"traceutil/trace.go:171","msg":"trace[1534952392] transaction","detail":"{read_only:false; response_revision:22446; number_of_response:1; }","duration":"115.659021ms","start":"2026-09-01T04:59:24.915231Z","end":"2026-09-01T04:59:25.03089Z","steps":["trace[1534952392] 'process raft request' (duration: 115.549638ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:02:19.686888Z","caller":"traceutil/trace.go:171","msg":"trace[194683894] transaction","detail":"{read_only:false; response_revision:24381; number_of_response:1; }","duration":"302.933683ms","start":"2026-09-01T05:02:19.383896Z","end":"2026-09-01T05:02:19.68683Z","steps":["trace[194683894] 'process raft request' (duration: 302.669305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:02:19.687229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:02:19.383873Z","time spent":"303.125328ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:02:19.698665Z","caller":"traceutil/trace.go:171","msg":"trace[940902781] transaction","detail":"{read_only:false; response_revision:24382; number_of_response:1; }","duration":"280.787877ms","start":"2026-09-01T05:02:19.417853Z","end":"2026-09-01T05:02:19.698641Z","steps":["trace[940902781] 'process raft request' (duration: 279.999754ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:02:19.69887Z","caller":"traceutil/trace.go:171","msg":"trace[2100203553] transaction","detail":"{read_only:false; response_revision:24383; number_of_response:1; }","duration":"123.235332ms","start":"2026-09-01T05:02:19.575622Z","end":"2026-09-01T05:02:19.698857Z","steps":["trace[2100203553] 'process raft request' (duration: 122.611143ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:02:34.51264Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21586} {"level":"info","ts":"2026-09-01T05:02:34.634938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21586,"took":"118.438996ms","hash":2719684295,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":42528768,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-01T05:02:34.634994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2719684295,"revision":21586,"compact-revision":19469} {"level":"info","ts":"2026-09-01T05:03:50.734903Z","caller":"traceutil/trace.go:171","msg":"trace[724622254] linearizableReadLoop","detail":"{readStateIndex:26171; appliedIndex:26170; }","duration":"102.17377ms","start":"2026-09-01T05:03:50.632703Z","end":"2026-09-01T05:03:50.734877Z","steps":["trace[724622254] 'read index received' (duration: 101.982674ms)","trace[724622254] 'applied index is now lower than readState.Index' (duration: 190.106µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:03:50.735033Z","caller":"traceutil/trace.go:171","msg":"trace[658533277] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"137.770474ms","start":"2026-09-01T05:03:50.59723Z","end":"2026-09-01T05:03:50.735001Z","steps":["trace[658533277] 'process raft request' (duration: 137.360582ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:03:50.735047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.328525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T05:03:50.735144Z","caller":"traceutil/trace.go:171","msg":"trace[195787963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25329; }","duration":"102.47303ms","start":"2026-09-01T05:03:50.632652Z","end":"2026-09-01T05:03:50.735125Z","steps":["trace[195787963] 'agreement among raft nodes before linearized reading' (duration: 102.333295ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:06:58.907462Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c2c.wal"} {"level":"info","ts":"2026-09-01T05:07:34.519662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24525} {"level":"info","ts":"2026-09-01T05:07:34.684078Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24525,"took":"158.088383ms","hash":593691633,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":33021952,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-01T05:07:34.6842Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":593691633,"revision":24525,"compact-revision":21586} {"level":"info","ts":"2026-09-01T05:08:59.165933Z","caller":"traceutil/trace.go:171","msg":"trace[1362134809] linearizableReadLoop","detail":"{readStateIndex:28595; appliedIndex:28594; }","duration":"121.140078ms","start":"2026-09-01T05:08:59.044761Z","end":"2026-09-01T05:08:59.165901Z","steps":["trace[1362134809] 'read index received' (duration: 120.822638ms)","trace[1362134809] 'applied index is now lower than readState.Index' (duration: 316.3µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:08:59.166036Z","caller":"traceutil/trace.go:171","msg":"trace[939783894] transaction","detail":"{read_only:false; response_revision:27685; number_of_response:1; }","duration":"138.058873ms","start":"2026-09-01T05:08:59.027922Z","end":"2026-09-01T05:08:59.165981Z","steps":["trace[939783894] 'process raft request' (duration: 137.781724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:08:59.166219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.435677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:4594"} {"level":"warn","ts":"2026-09-01T05:08:59.166254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.536334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19106"} {"level":"info","ts":"2026-09-01T05:08:59.166299Z","caller":"traceutil/trace.go:171","msg":"trace[787309537] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:27685; }","duration":"121.562331ms","start":"2026-09-01T05:08:59.044718Z","end":"2026-09-01T05:08:59.16628Z","steps":["trace[787309537] 'agreement among raft nodes before linearized reading' (duration: 121.339214ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:08:59.166302Z","caller":"traceutil/trace.go:171","msg":"trace[2057672493] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27685; }","duration":"107.629987ms","start":"2026-09-01T05:08:59.058656Z","end":"2026-09-01T05:08:59.166286Z","steps":["trace[2057672493] 'agreement among raft nodes before linearized reading' (duration: 107.431051ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:12:02.366545Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":30006,"local-member-snapshot-index":20005,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T05:12:02.375573Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30006} {"level":"info","ts":"2026-09-01T05:12:02.37571Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25006} {"level":"info","ts":"2026-09-01T05:12:34.529719Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27069} {"level":"info","ts":"2026-09-01T05:12:34.672856Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27069,"took":"137.719994ms","hash":308892263,"current-db-size-bytes":57589760,"current-db-size":"58 MB","current-db-size-in-use-bytes":29753344,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T05:12:34.672941Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":308892263,"revision":27069,"compact-revision":24525}