{"level":"warn","ts":"2026-05-04T01:39:14.415013Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T01:39:14.415123Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30: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-05-04T01:39:14.415202Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T01:39:14.415217Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-04T01:39:14.415241Z","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-05-04T01:39:14.415815Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-05-04T01:39:14.415897Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30: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-05-04T01:39:14.420564Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.425898ms"} {"level":"info","ts":"2026-05-04T01:39:14.432719Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-05-04T01:39:14.432804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-05-04T01:39:14.432827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-05-04T01:39:14.432841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-04T01:39:14.432851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-05-04T01:39:14.432908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-05-04T01:39:14.440466Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-04T01:39:14.444089Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-04T01:39:14.446551Z","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-05-04T01:39:14.449288Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-04T01:39:14.449986Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-04T01:39:14.45006Z","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-05-04T01:39:14.450196Z","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-05-04T01:39:14.45021Z","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-05-04T01:39:14.450731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-05-04T01:39:14.450853Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-04T01:39:14.452837Z","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-05-04T01:39:14.452867Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-04T01:39:14.452898Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-04T01:39:14.453109Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-04T01:39:14.453156Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-04T01:39:14.833735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-05-04T01:39:14.833816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-04T01:39:14.833862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-05-04T01:39:14.83389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-05-04T01:39:14.833902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-04T01:39:14.833919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-05-04T01:39:14.833941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-04T01:39:14.844811Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:39:14.847165Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-05-04T01:39:14.847177Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T01:39:14.847213Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T01:39:14.847539Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-04T01:39:14.847583Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-04T01:39:14.850894Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-05-04T01:39:14.851364Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-04T01:39:14.866816Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:39:14.866887Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:39:14.866955Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:39:17.387805Z","caller":"traceutil/trace.go:171","msg":"trace[1089669637] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"128.158185ms","start":"2026-05-04T01:39:17.259618Z","end":"2026-05-04T01:39:17.387776Z","steps":["trace[1089669637] 'read index received' (duration: 69.481516ms)","trace[1089669637] 'applied index is now lower than readState.Index' (duration: 58.674769ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:39:17.387843Z","caller":"traceutil/trace.go:171","msg":"trace[1543279666] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"206.696974ms","start":"2026-05-04T01:39:17.181096Z","end":"2026-05-04T01:39:17.387793Z","steps":["trace[1543279666] 'process raft request' (duration: 148.013696ms)","trace[1543279666] 'compare' (duration: 58.464432ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:39:17.387997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.35289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-04T01:39:17.388063Z","caller":"traceutil/trace.go:171","msg":"trace[317142371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"128.465113ms","start":"2026-05-04T01:39:17.259586Z","end":"2026-05-04T01:39:17.388051Z","steps":["trace[317142371] 'agreement among raft nodes before linearized reading' (duration: 128.309509ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:39:18.58233Z","caller":"traceutil/trace.go:171","msg":"trace[400983804] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"113.144155ms","start":"2026-05-04T01:39:18.469153Z","end":"2026-05-04T01:39:18.582297Z","steps":["trace[400983804] 'process raft request' (duration: 70.179146ms)","trace[400983804] 'compare' (duration: 42.817645ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:39:24.572534Z","caller":"traceutil/trace.go:171","msg":"trace[1242012078] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"151.19046ms","start":"2026-05-04T01:39:24.421311Z","end":"2026-05-04T01:39:24.572502Z","steps":["trace[1242012078] 'process raft request' (duration: 92.634525ms)","trace[1242012078] 'compare' (duration: 58.423802ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:39:52.414949Z","caller":"traceutil/trace.go:171","msg":"trace[766770018] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"421.784005ms","start":"2026-05-04T01:39:51.993141Z","end":"2026-05-04T01:39:52.414925Z","steps":["trace[766770018] 'process raft request' (duration: 421.336233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:52.415429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:51.993115Z","time spent":"421.911139ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:39:52.591291Z","caller":"traceutil/trace.go:171","msg":"trace[1054041698] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"121.691322ms","start":"2026-05-04T01:39:52.469552Z","end":"2026-05-04T01:39:52.591243Z","steps":["trace[1054041698] 'process raft request' (duration: 54.145657ms)","trace[1054041698] 'compare' (duration: 67.084953ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:39:53.182795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.018396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:39:53.182937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.056481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:39:53.182824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.119695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:39:53.183066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.760497ms","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-05-04T01:39:53.183058Z","caller":"traceutil/trace.go:171","msg":"trace[249037564] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:578; }","duration":"394.377861ms","start":"2026-05-04T01:39:52.78866Z","end":"2026-05-04T01:39:53.183038Z","steps":["trace[249037564] 'count revisions from in-memory index tree' (duration: 394.077723ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:39:53.183125Z","caller":"traceutil/trace.go:171","msg":"trace[2038171572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"215.807498ms","start":"2026-05-04T01:39:52.967297Z","end":"2026-05-04T01:39:53.183104Z","steps":["trace[2038171572] 'range keys from in-memory index tree' (duration: 215.594732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.183222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:52.788653Z","time spent":"394.495114ms","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-04T01:39:53.183247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.467331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:39:53.183353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.938057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.183397Z","caller":"traceutil/trace.go:171","msg":"trace[220758955] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:578; }","duration":"207.99893ms","start":"2026-05-04T01:39:52.975386Z","end":"2026-05-04T01:39:53.183385Z","steps":["trace[220758955] 'range keys from in-memory index tree' (duration: 207.884006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.183536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.80542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.183582Z","caller":"traceutil/trace.go:171","msg":"trace[2074888033] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:578; }","duration":"240.008434ms","start":"2026-05-04T01:39:52.943562Z","end":"2026-05-04T01:39:53.18357Z","steps":["trace[2074888033] 'count revisions from in-memory index tree' (duration: 239.745728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.183627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.890096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.183695Z","caller":"traceutil/trace.go:171","msg":"trace[17597648] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:578; }","duration":"207.986339ms","start":"2026-05-04T01:39:52.975692Z","end":"2026-05-04T01:39:53.183679Z","steps":["trace[17597648] 'count revisions from in-memory index tree' (duration: 207.779503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.183701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.054761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.183766Z","caller":"traceutil/trace.go:171","msg":"trace[2092686346] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:578; }","duration":"379.251147ms","start":"2026-05-04T01:39:52.804495Z","end":"2026-05-04T01:39:53.183746Z","steps":["trace[2092686346] 'range keys from in-memory index tree' (duration: 378.948459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.183822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:52.80449Z","time spent":"379.316989ms","remote":"127.0.0.1:56028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-05-04T01:39:53.183967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.252854ms","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-05-04T01:39:53.184027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.156242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.184053Z","caller":"traceutil/trace.go:171","msg":"trace[367321413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"478.368476ms","start":"2026-05-04T01:39:52.705674Z","end":"2026-05-04T01:39:53.184043Z","steps":["trace[367321413] 'range keys from in-memory index tree' (duration: 478.10043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.184151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:52.705656Z","time spent":"478.48717ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-04T01:39:53.183326Z","caller":"traceutil/trace.go:171","msg":"trace[1385089134] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:578; }","duration":"251.561414ms","start":"2026-05-04T01:39:52.931752Z","end":"2026-05-04T01:39:53.183313Z","steps":["trace[1385089134] 'range keys from in-memory index tree' (duration: 251.42603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.184153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.705109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.184559Z","caller":"traceutil/trace.go:171","msg":"trace[1511007319] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:578; }","duration":"380.124851ms","start":"2026-05-04T01:39:52.804421Z","end":"2026-05-04T01:39:53.184546Z","steps":["trace[1511007319] 'count revisions from in-memory index tree' (duration: 379.661008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.184599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.313947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.18466Z","caller":"traceutil/trace.go:171","msg":"trace[1005977885] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:578; }","duration":"361.42269ms","start":"2026-05-04T01:39:52.823223Z","end":"2026-05-04T01:39:53.184646Z","steps":["trace[1005977885] 'count revisions from in-memory index tree' (duration: 361.249906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.184678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.428281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:39:53.184704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:52.823206Z","time spent":"361.485753ms","remote":"127.0.0.1:56036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-04T01:39:53.184731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.194783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:39:53.184728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:52.804414Z","time spent":"380.224163ms","remote":"127.0.0.1:56028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-05-04T01:39:53.184799Z","caller":"traceutil/trace.go:171","msg":"trace[472340969] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:578; }","duration":"380.311965ms","start":"2026-05-04T01:39:52.804469Z","end":"2026-05-04T01:39:53.184781Z","steps":["trace[472340969] 'range keys from in-memory index tree' (duration: 379.954096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.184843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:52.804451Z","time spent":"380.381187ms","remote":"127.0.0.1:56028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-05-04T01:39:53.182906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.758172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.184971Z","caller":"traceutil/trace.go:171","msg":"trace[491827118] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:578; }","duration":"240.865148ms","start":"2026-05-04T01:39:52.944093Z","end":"2026-05-04T01:39:53.184958Z","steps":["trace[491827118] 'range keys from in-memory index tree' (duration: 238.689659ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:39:53.182996Z","caller":"traceutil/trace.go:171","msg":"trace[340780480] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:578; }","duration":"260.143464ms","start":"2026-05-04T01:39:52.922831Z","end":"2026-05-04T01:39:53.182974Z","steps":["trace[340780480] 'range keys from in-memory index tree' (duration: 259.982409ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:39:53.182956Z","caller":"traceutil/trace.go:171","msg":"trace[945916253] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:578; }","duration":"356.208381ms","start":"2026-05-04T01:39:52.826724Z","end":"2026-05-04T01:39:53.182933Z","steps":["trace[945916253] 'range keys from in-memory index tree' (duration: 355.932204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.182899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.066831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.184079Z","caller":"traceutil/trace.go:171","msg":"trace[427236521] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:578; }","duration":"395.247785ms","start":"2026-05-04T01:39:52.788816Z","end":"2026-05-04T01:39:53.184064Z","steps":["trace[427236521] 'range keys from in-memory index tree' (duration: 395.07682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.184508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.055656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.185549Z","caller":"traceutil/trace.go:171","msg":"trace[844234756] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:578; }","duration":"397.113985ms","start":"2026-05-04T01:39:52.788403Z","end":"2026-05-04T01:39:53.185517Z","steps":["trace[844234756] 'range keys from in-memory index tree' (duration: 395.982514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.185541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:52.826691Z","time spent":"358.823802ms","remote":"127.0.0.1:56036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"info","ts":"2026-05-04T01:39:53.184719Z","caller":"traceutil/trace.go:171","msg":"trace[1252497567] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:578; }","duration":"361.483002ms","start":"2026-05-04T01:39:52.823223Z","end":"2026-05-04T01:39:53.184706Z","steps":["trace[1252497567] 'range keys from in-memory index tree' (duration: 361.337009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.185601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:52.788382Z","time spent":"397.199956ms","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-04T01:39:53.185661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:52.788796Z","time spent":"396.845967ms","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-04T01:39:53.182824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.09786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:39:53.185693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:39:52.823213Z","time spent":"362.427497ms","remote":"127.0.0.1:56036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"info","ts":"2026-05-04T01:39:53.185642Z","caller":"traceutil/trace.go:171","msg":"trace[158538183] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:578; }","duration":"262.782914ms","start":"2026-05-04T01:39:52.922794Z","end":"2026-05-04T01:39:53.185577Z","steps":["trace[158538183] 'count revisions from in-memory index tree' (duration: 260.006299ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:39:53.185734Z","caller":"traceutil/trace.go:171","msg":"trace[524788358] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:578; }","duration":"242.097771ms","start":"2026-05-04T01:39:52.943628Z","end":"2026-05-04T01:39:53.185726Z","steps":["trace[524788358] 'range keys from in-memory index tree' (duration: 238.991078ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:39:53.185847Z","caller":"traceutil/trace.go:171","msg":"trace[455467086] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"183.192756ms","start":"2026-05-04T01:39:53.002628Z","end":"2026-05-04T01:39:53.18582Z","steps":["trace[455467086] 'read index received' (duration: 181.0954ms)","trace[455467086] 'applied index is now lower than readState.Index' (duration: 2.096186ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:39:53.189067Z","caller":"traceutil/trace.go:171","msg":"trace[695630320] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"192.7016ms","start":"2026-05-04T01:39:52.996313Z","end":"2026-05-04T01:39:53.189015Z","steps":["trace[695630320] 'process raft request' (duration: 187.884451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.191685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.990631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.19179Z","caller":"traceutil/trace.go:171","msg":"trace[43217917] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:579; }","duration":"189.120424ms","start":"2026-05-04T01:39:53.002631Z","end":"2026-05-04T01:39:53.191751Z","steps":["trace[43217917] 'agreement among raft nodes before linearized reading' (duration: 188.564649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.192245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.611258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:39:53.192254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.574375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.192307Z","caller":"traceutil/trace.go:171","msg":"trace[1105703380] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:579; }","duration":"189.69657ms","start":"2026-05-04T01:39:53.002586Z","end":"2026-05-04T01:39:53.192283Z","steps":["trace[1105703380] 'agreement among raft nodes before linearized reading' (duration: 183.30989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.192693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.640603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.192755Z","caller":"traceutil/trace.go:171","msg":"trace[683700752] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:579; }","duration":"157.742306ms","start":"2026-05-04T01:39:53.034998Z","end":"2026-05-04T01:39:53.19274Z","steps":["trace[683700752] 'agreement among raft nodes before linearized reading' (duration: 157.658423ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:39:53.192347Z","caller":"traceutil/trace.go:171","msg":"trace[1151279940] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:579; }","duration":"156.689438ms","start":"2026-05-04T01:39:53.035619Z","end":"2026-05-04T01:39:53.192308Z","steps":["trace[1151279940] 'agreement among raft nodes before linearized reading' (duration: 156.39992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.192938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.832739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.19299Z","caller":"traceutil/trace.go:171","msg":"trace[1311402570] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:579; }","duration":"157.88705ms","start":"2026-05-04T01:39:53.035087Z","end":"2026-05-04T01:39:53.192974Z","steps":["trace[1311402570] 'agreement among raft nodes before linearized reading' (duration: 157.820308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:39:53.193371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.406569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:39:53.193391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.865646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:39:53.193423Z","caller":"traceutil/trace.go:171","msg":"trace[1203342482] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:579; }","duration":"190.46003ms","start":"2026-05-04T01:39:53.002942Z","end":"2026-05-04T01:39:53.193402Z","steps":["trace[1203342482] 'agreement among raft nodes before linearized reading' (duration: 190.035749ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:39:53.193674Z","caller":"traceutil/trace.go:171","msg":"trace[2018049363] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:579; }","duration":"100.982159ms","start":"2026-05-04T01:39:53.092451Z","end":"2026-05-04T01:39:53.193433Z","steps":["trace[2018049363] 'agreement among raft nodes before linearized reading' (duration: 100.512067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:41:21.470468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.801965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T01:41:21.470737Z","caller":"traceutil/trace.go:171","msg":"trace[1384404166] linearizableReadLoop","detail":"{readStateIndex:1837; appliedIndex:1836; }","duration":"254.314963ms","start":"2026-05-04T01:41:21.216404Z","end":"2026-05-04T01:41:21.470719Z","steps":["trace[1384404166] 'read index received' (duration: 43.381µs)","trace[1384404166] 'applied index is now lower than readState.Index' (duration: 254.269872ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:41:21.470904Z","caller":"traceutil/trace.go:171","msg":"trace[1884474567] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"310.559981ms","start":"2026-05-04T01:41:21.160266Z","end":"2026-05-04T01:41:21.470826Z","steps":["trace[1884474567] 'process raft request' (duration: 50.059807ms)","trace[1884474567] 'compare' (duration: 259.544607ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:41:21.471087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.674963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-05-04T01:41:21.471182Z","caller":"traceutil/trace.go:171","msg":"trace[1868603597] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1783; }","duration":"254.789786ms","start":"2026-05-04T01:41:21.216354Z","end":"2026-05-04T01:41:21.471144Z","steps":["trace[1868603597] 'agreement among raft nodes before linearized reading' (duration: 254.483508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:41:21.471183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:41:21.160248Z","time spent":"310.772766ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:41:21.471562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.213213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:523"} {"level":"info","ts":"2026-05-04T01:41:21.471642Z","caller":"traceutil/trace.go:171","msg":"trace[355670508] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1783; }","duration":"205.344677ms","start":"2026-05-04T01:41:21.26628Z","end":"2026-05-04T01:41:21.471625Z","steps":["trace[355670508] 'agreement among raft nodes before linearized reading' (duration: 205.135151ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:21.647625Z","caller":"traceutil/trace.go:171","msg":"trace[1836492287] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"106.254132ms","start":"2026-05-04T01:41:21.541345Z","end":"2026-05-04T01:41:21.647599Z","steps":["trace[1836492287] 'process raft request' (duration: 105.980934ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:21.647977Z","caller":"traceutil/trace.go:171","msg":"trace[1233716850] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"106.334555ms","start":"2026-05-04T01:41:21.541617Z","end":"2026-05-04T01:41:21.647951Z","steps":["trace[1233716850] 'process raft request' (duration: 106.203821ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:21.648131Z","caller":"traceutil/trace.go:171","msg":"trace[1545870050] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"106.489339ms","start":"2026-05-04T01:41:21.541509Z","end":"2026-05-04T01:41:21.647998Z","steps":["trace[1545870050] 'process raft request' (duration: 105.990475ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:42:03.839254Z","caller":"traceutil/trace.go:171","msg":"trace[1502627766] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"107.276114ms","start":"2026-05-04T01:42:03.731955Z","end":"2026-05-04T01:42:03.839231Z","steps":["trace[1502627766] 'process raft request' (duration: 107.12871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:42:04.099711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.849728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T01:42:04.09982Z","caller":"traceutil/trace.go:171","msg":"trace[1370467957] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2195; }","duration":"287.510236ms","start":"2026-05-04T01:42:03.812293Z","end":"2026-05-04T01:42:04.099803Z","steps":["trace[1370467957] 'read index received' (duration: 26.796048ms)","trace[1370467957] 'applied index is now lower than readState.Index' (duration: 260.712788ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:42:04.099909Z","caller":"traceutil/trace.go:171","msg":"trace[718293095] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"367.074548ms","start":"2026-05-04T01:42:03.732824Z","end":"2026-05-04T01:42:04.099898Z","steps":["trace[718293095] 'process raft request' (duration: 241.945282ms)","trace[718293095] 'compare' (duration: 124.716204ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:42:04.099974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:42:03.732805Z","time spent":"367.139509ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:42:04.100083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.751084ms","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-05-04T01:42:04.100106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.405529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-04T01:42:04.100178Z","caller":"traceutil/trace.go:171","msg":"trace[1746350060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2126; }","duration":"153.891258ms","start":"2026-05-04T01:42:03.946262Z","end":"2026-05-04T01:42:04.100153Z","steps":["trace[1746350060] 'agreement among raft nodes before linearized reading' (duration: 153.664252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:42:04.100159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.133459ms","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-05-04T01:42:04.100249Z","caller":"traceutil/trace.go:171","msg":"trace[315439463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2126; }","duration":"152.247343ms","start":"2026-05-04T01:42:03.947978Z","end":"2026-05-04T01:42:04.100225Z","steps":["trace[315439463] 'agreement among raft nodes before linearized reading' (duration: 151.992986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:42:04.100298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.989059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-05-04T01:42:04.100331Z","caller":"traceutil/trace.go:171","msg":"trace[387777464] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2126; }","duration":"288.072112ms","start":"2026-05-04T01:42:03.812248Z","end":"2026-05-04T01:42:04.10032Z","steps":["trace[387777464] 'agreement among raft nodes before linearized reading' (duration: 287.962149ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:42:04.100182Z","caller":"traceutil/trace.go:171","msg":"trace[23024334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2126; }","duration":"151.507832ms","start":"2026-05-04T01:42:03.948652Z","end":"2026-05-04T01:42:04.10016Z","steps":["trace[23024334] 'agreement among raft nodes before linearized reading' (duration: 151.278636ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:42:04.223205Z","caller":"traceutil/trace.go:171","msg":"trace[2055423108] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"118.418341ms","start":"2026-05-04T01:42:04.104727Z","end":"2026-05-04T01:42:04.223145Z","steps":["trace[2055423108] 'process raft request' (duration: 118.242066ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:42:04.224117Z","caller":"traceutil/trace.go:171","msg":"trace[319732968] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"119.144711ms","start":"2026-05-04T01:42:04.104959Z","end":"2026-05-04T01:42:04.224104Z","steps":["trace[319732968] 'process raft request' (duration: 119.10844ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:42:04.22428Z","caller":"traceutil/trace.go:171","msg":"trace[1527349432] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"119.311926ms","start":"2026-05-04T01:42:04.10493Z","end":"2026-05-04T01:42:04.224242Z","steps":["trace[1527349432] 'process raft request' (duration: 119.052609ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:30.982021Z","caller":"traceutil/trace.go:171","msg":"trace[1745332702] linearizableReadLoop","detail":"{readStateIndex:2878; appliedIndex:2877; }","duration":"277.872539ms","start":"2026-05-04T01:43:30.704133Z","end":"2026-05-04T01:43:30.982005Z","steps":["trace[1745332702] 'read index received' (duration: 260.056868ms)","trace[1745332702] 'applied index is now lower than readState.Index' (duration: 17.814741ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:43:30.982118Z","caller":"traceutil/trace.go:171","msg":"trace[1670658988] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"288.42412ms","start":"2026-05-04T01:43:30.693686Z","end":"2026-05-04T01:43:30.98211Z","steps":["trace[1670658988] 'process raft request' (duration: 270.562908ms)","trace[1670658988] 'compare' (duration: 17.516423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:43:30.982263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.118777ms","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-05-04T01:43:30.982296Z","caller":"traceutil/trace.go:171","msg":"trace[590432003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2770; }","duration":"278.200759ms","start":"2026-05-04T01:43:30.704087Z","end":"2026-05-04T01:43:30.982288Z","steps":["trace[590432003] 'agreement among raft nodes before linearized reading' (duration: 278.072325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:30.982398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.208049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-04T01:43:30.982437Z","caller":"traceutil/trace.go:171","msg":"trace[488820981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2770; }","duration":"278.26293ms","start":"2026-05-04T01:43:30.704168Z","end":"2026-05-04T01:43:30.982431Z","steps":["trace[488820981] 'agreement among raft nodes before linearized reading' (duration: 278.191048ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:33.126992Z","caller":"traceutil/trace.go:171","msg":"trace[227858253] linearizableReadLoop","detail":"{readStateIndex:2891; appliedIndex:2890; }","duration":"141.756668ms","start":"2026-05-04T01:43:32.985209Z","end":"2026-05-04T01:43:33.126966Z","steps":["trace[227858253] 'read index received' (duration: 141.596093ms)","trace[227858253] 'applied index is now lower than readState.Index' (duration: 159.385µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:43:33.127067Z","caller":"traceutil/trace.go:171","msg":"trace[918914384] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"269.401886ms","start":"2026-05-04T01:43:32.857623Z","end":"2026-05-04T01:43:33.127024Z","steps":["trace[918914384] 'process raft request' (duration: 269.16436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:33.127398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.158538ms","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-05-04T01:43:33.127554Z","caller":"traceutil/trace.go:171","msg":"trace[252517604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2782; }","duration":"142.325383ms","start":"2026-05-04T01:43:32.985166Z","end":"2026-05-04T01:43:33.127491Z","steps":["trace[252517604] 'agreement among raft nodes before linearized reading' (duration: 141.915742ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:33.12883Z","caller":"traceutil/trace.go:171","msg":"trace[1376387058] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"117.615162ms","start":"2026-05-04T01:43:33.01119Z","end":"2026-05-04T01:43:33.128805Z","steps":["trace[1376387058] 'process raft request' (duration: 117.435797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:33.128946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.728851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-04T01:43:33.129032Z","caller":"traceutil/trace.go:171","msg":"trace[461847545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2783; }","duration":"127.830344ms","start":"2026-05-04T01:43:33.001181Z","end":"2026-05-04T01:43:33.129012Z","steps":["trace[461847545] 'agreement among raft nodes before linearized reading' (duration: 127.596197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:33.1292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.601633ms","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-05-04T01:43:33.129233Z","caller":"traceutil/trace.go:171","msg":"trace[1845410618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2783; }","duration":"125.658794ms","start":"2026-05-04T01:43:33.003566Z","end":"2026-05-04T01:43:33.129225Z","steps":["trace[1845410618] 'agreement among raft nodes before linearized reading' (duration: 125.574251ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:41.195026Z","caller":"traceutil/trace.go:171","msg":"trace[144171498] transaction","detail":"{read_only:false; response_revision:2887; number_of_response:1; }","duration":"277.807237ms","start":"2026-05-04T01:43:40.917001Z","end":"2026-05-04T01:43:41.194808Z","steps":["trace[144171498] 'process raft request' (duration: 277.644063ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:42.562849Z","caller":"traceutil/trace.go:171","msg":"trace[1690595499] transaction","detail":"{read_only:false; response_revision:2895; number_of_response:1; }","duration":"394.867574ms","start":"2026-05-04T01:43:42.167955Z","end":"2026-05-04T01:43:42.562823Z","steps":["trace[1690595499] 'process raft request' (duration: 394.689929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:42.563008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:43:42.167947Z","time spent":"394.982937ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:43:42.567306Z","caller":"traceutil/trace.go:171","msg":"trace[282489294] linearizableReadLoop","detail":"{readStateIndex:3012; appliedIndex:3010; }","duration":"202.359518ms","start":"2026-05-04T01:43:42.364928Z","end":"2026-05-04T01:43:42.567288Z","steps":["trace[282489294] 'read index received' (duration: 197.72683ms)","trace[282489294] 'applied index is now lower than readState.Index' (duration: 4.631918ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:43:42.56735Z","caller":"traceutil/trace.go:171","msg":"trace[314019125] transaction","detail":"{read_only:false; response_revision:2896; number_of_response:1; }","duration":"398.375421ms","start":"2026-05-04T01:43:42.168949Z","end":"2026-05-04T01:43:42.567324Z","steps":["trace[314019125] 'process raft request' (duration: 398.233397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:42.567472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:43:42.168943Z","time spent":"398.461553ms","remote":"127.0.0.1:46552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:43:42.567659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.712128ms","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-05-04T01:43:42.567836Z","caller":"traceutil/trace.go:171","msg":"trace[1782664615] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2896; }","duration":"202.927303ms","start":"2026-05-04T01:43:42.364878Z","end":"2026-05-04T01:43:42.567805Z","steps":["trace[1782664615] 'agreement among raft nodes before linearized reading' (duration: 202.489161ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:44.784953Z","caller":"traceutil/trace.go:171","msg":"trace[2006912774] linearizableReadLoop","detail":"{readStateIndex:3071; appliedIndex:3070; }","duration":"203.783038ms","start":"2026-05-04T01:43:44.581145Z","end":"2026-05-04T01:43:44.784928Z","steps":["trace[2006912774] 'read index received' (duration: 203.646814ms)","trace[2006912774] 'applied index is now lower than readState.Index' (duration: 133.114µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:43:44.784985Z","caller":"traceutil/trace.go:171","msg":"trace[635324046] transaction","detail":"{read_only:false; response_revision:2953; number_of_response:1; }","duration":"205.645788ms","start":"2026-05-04T01:43:44.579306Z","end":"2026-05-04T01:43:44.784952Z","steps":["trace[635324046] 'process raft request' (duration: 205.483544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:44.785145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.262704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:43:44.785206Z","caller":"traceutil/trace.go:171","msg":"trace[282126328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2953; }","duration":"170.366136ms","start":"2026-05-04T01:43:44.614824Z","end":"2026-05-04T01:43:44.78519Z","steps":["trace[282126328] 'agreement among raft nodes before linearized reading' (duration: 170.261183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:44.78554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.370354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-05-04T01:43:44.785663Z","caller":"traceutil/trace.go:171","msg":"trace[1460417398] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2953; }","duration":"204.505338ms","start":"2026-05-04T01:43:44.581102Z","end":"2026-05-04T01:43:44.785608Z","steps":["trace[1460417398] 'agreement among raft nodes before linearized reading' (duration: 203.934062ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:45.09655Z","caller":"traceutil/trace.go:171","msg":"trace[368149649] transaction","detail":"{read_only:false; response_revision:2956; number_of_response:1; }","duration":"270.915228ms","start":"2026-05-04T01:43:44.825613Z","end":"2026-05-04T01:43:45.096528Z","steps":["trace[368149649] 'process raft request' (duration: 270.865096ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:45.096608Z","caller":"traceutil/trace.go:171","msg":"trace[1440115078] transaction","detail":"{read_only:false; response_revision:2955; number_of_response:1; }","duration":"299.119465ms","start":"2026-05-04T01:43:44.797456Z","end":"2026-05-04T01:43:45.096576Z","steps":["trace[1440115078] 'process raft request' (duration: 295.31429ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:44:14.02064Z","caller":"traceutil/trace.go:171","msg":"trace[971276781] transaction","detail":"{read_only:false; response_revision:3230; number_of_response:1; }","duration":"242.010759ms","start":"2026-05-04T01:44:13.778573Z","end":"2026-05-04T01:44:14.020583Z","steps":["trace[971276781] 'process raft request' (duration: 241.726581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:44:15.856778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.860313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T01:44:15.856987Z","caller":"traceutil/trace.go:171","msg":"trace[1590342862] transaction","detail":"{read_only:false; response_revision:3241; number_of_response:1; }","duration":"470.709801ms","start":"2026-05-04T01:44:15.38625Z","end":"2026-05-04T01:44:15.85696Z","steps":["trace[1590342862] 'compare' (duration: 438.009356ms)","trace[1590342862] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:415; } (duration: 28.8458ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:44:15.857058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:44:15.386229Z","time spent":"470.795233ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:44:15.857408Z","caller":"traceutil/trace.go:171","msg":"trace[223464993] linearizableReadLoop","detail":"{readStateIndex:3372; appliedIndex:3370; }","duration":"445.722948ms","start":"2026-05-04T01:44:15.411648Z","end":"2026-05-04T01:44:15.857371Z","steps":["trace[223464993] 'read index received' (duration: 227.147µs)","trace[223464993] 'applied index is now lower than readState.Index' (duration: 445.493841ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:44:15.857618Z","caller":"traceutil/trace.go:171","msg":"trace[97260644] transaction","detail":"{read_only:false; response_revision:3242; number_of_response:1; }","duration":"469.9057ms","start":"2026-05-04T01:44:15.387649Z","end":"2026-05-04T01:44:15.857555Z","steps":["trace[97260644] 'process raft request' (duration: 469.248142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:44:15.857794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.099478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-05-04T01:44:15.857949Z","caller":"traceutil/trace.go:171","msg":"trace[652970692] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3242; }","duration":"446.291902ms","start":"2026-05-04T01:44:15.411616Z","end":"2026-05-04T01:44:15.857907Z","steps":["trace[652970692] 'agreement among raft nodes before linearized reading' (duration: 445.909792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:44:15.858088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:44:15.411602Z","time spent":"446.449197ms","remote":"127.0.0.1:46552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-04T01:44:15.85789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:44:15.387635Z","time spent":"470.108845ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:45:41.069422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.381557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:781801"} {"level":"info","ts":"2026-05-04T01:45:41.069489Z","caller":"traceutil/trace.go:171","msg":"trace[561038999] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4506; }","duration":"106.477739ms","start":"2026-05-04T01:45:40.963Z","end":"2026-05-04T01:45:41.069478Z","steps":["trace[561038999] 'range keys from in-memory index tree' (duration: 106.105629ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:54.746961Z","caller":"traceutil/trace.go:171","msg":"trace[1505913164] linearizableReadLoop","detail":"{readStateIndex:4959; appliedIndex:4958; }","duration":"265.969771ms","start":"2026-05-04T01:45:54.480959Z","end":"2026-05-04T01:45:54.746928Z","steps":["trace[1505913164] 'read index received' (duration: 265.714284ms)","trace[1505913164] 'applied index is now lower than readState.Index' (duration: 254.167µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:45:54.747102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.120825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T01:45:54.747079Z","caller":"traceutil/trace.go:171","msg":"trace[228691436] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"299.425679ms","start":"2026-05-04T01:45:54.447605Z","end":"2026-05-04T01:45:54.747031Z","steps":["trace[228691436] 'process raft request' (duration: 299.140011ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:54.747137Z","caller":"traceutil/trace.go:171","msg":"trace[1683982934] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:4758; }","duration":"266.211497ms","start":"2026-05-04T01:45:54.480916Z","end":"2026-05-04T01:45:54.747128Z","steps":["trace[1683982934] 'agreement among raft nodes before linearized reading' (duration: 266.124495ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:55.035911Z","caller":"traceutil/trace.go:171","msg":"trace[587451144] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"283.428552ms","start":"2026-05-04T01:45:54.752449Z","end":"2026-05-04T01:45:55.035877Z","steps":["trace[587451144] 'process raft request' (duration: 279.039131ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:55.035944Z","caller":"traceutil/trace.go:171","msg":"trace[1758211630] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"283.384041ms","start":"2026-05-04T01:45:54.752527Z","end":"2026-05-04T01:45:55.035911Z","steps":["trace[1758211630] 'process raft request' (duration: 283.226066ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:55.036012Z","caller":"traceutil/trace.go:171","msg":"trace[1623844556] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"283.318099ms","start":"2026-05-04T01:45:54.75267Z","end":"2026-05-04T01:45:55.035988Z","steps":["trace[1623844556] 'process raft request' (duration: 283.179065ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:55.036023Z","caller":"traceutil/trace.go:171","msg":"trace[311120451] linearizableReadLoop","detail":"{readStateIndex:4963; appliedIndex:4959; }","duration":"121.313186ms","start":"2026-05-04T01:45:54.914617Z","end":"2026-05-04T01:45:55.03593Z","steps":["trace[311120451] 'read index received' (duration: 116.888375ms)","trace[311120451] 'applied index is now lower than readState.Index' (duration: 4.423921ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:45:55.037798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.162086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-04T01:45:55.037889Z","caller":"traceutil/trace.go:171","msg":"trace[1993795712] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4762; }","duration":"123.30103ms","start":"2026-05-04T01:45:54.914564Z","end":"2026-05-04T01:45:55.037865Z","steps":["trace[1993795712] 'agreement among raft nodes before linearized reading' (duration: 121.774218ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:55.036146Z","caller":"traceutil/trace.go:171","msg":"trace[1039675253] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"283.033471ms","start":"2026-05-04T01:45:54.7531Z","end":"2026-05-04T01:45:55.036134Z","steps":["trace[1039675253] 'process raft request' (duration: 282.790364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:46:01.263426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.111157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-05-04T01:46:01.263511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.640294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-04T01:46:01.263635Z","caller":"traceutil/trace.go:171","msg":"trace[1635577313] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4924; }","duration":"168.824459ms","start":"2026-05-04T01:46:01.094785Z","end":"2026-05-04T01:46:01.263609Z","steps":["trace[1635577313] 'range keys from in-memory index tree' (duration: 168.394417ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:46:01.263538Z","caller":"traceutil/trace.go:171","msg":"trace[1171524132] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4924; }","duration":"161.273391ms","start":"2026-05-04T01:46:01.102239Z","end":"2026-05-04T01:46:01.263512Z","steps":["trace[1171524132] 'range keys from in-memory index tree' (duration: 160.82443ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:49:07.320114Z","caller":"traceutil/trace.go:171","msg":"trace[1207581905] linearizableReadLoop","detail":"{readStateIndex:6794; appliedIndex:6793; }","duration":"446.996044ms","start":"2026-05-04T01:49:06.873095Z","end":"2026-05-04T01:49:07.320091Z","steps":["trace[1207581905] 'read index received' (duration: 446.791509ms)","trace[1207581905] 'applied index is now lower than readState.Index' (duration: 203.675µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:49:07.320232Z","caller":"traceutil/trace.go:171","msg":"trace[64955883] transaction","detail":"{read_only:false; response_revision:6510; number_of_response:1; }","duration":"509.724926ms","start":"2026-05-04T01:49:06.810498Z","end":"2026-05-04T01:49:07.320223Z","steps":["trace[64955883] 'process raft request' (duration: 509.440008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:49:07.320379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:49:06.810487Z","time spent":"509.791498ms","remote":"127.0.0.1:44766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:49:07.320583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.496708ms","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-05-04T01:49:07.320621Z","caller":"traceutil/trace.go:171","msg":"trace[1252708338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6510; }","duration":"447.57183ms","start":"2026-05-04T01:49:06.873039Z","end":"2026-05-04T01:49:07.320611Z","steps":["trace[1252708338] 'agreement among raft nodes before linearized reading' (duration: 447.458507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:49:07.320649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:49:06.873017Z","time spent":"447.626431ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-04T01:49:07.320896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.150643ms","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-05-04T01:49:07.321009Z","caller":"traceutil/trace.go:171","msg":"trace[1148386083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6510; }","duration":"428.329458ms","start":"2026-05-04T01:49:06.892654Z","end":"2026-05-04T01:49:07.320983Z","steps":["trace[1148386083] 'agreement among raft nodes before linearized reading' (duration: 428.04387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:49:07.321029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.23268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:49:07.321063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:49:06.892632Z","time spent":"428.41659ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-04T01:49:07.321092Z","caller":"traceutil/trace.go:171","msg":"trace[1953409064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6510; }","duration":"409.332033ms","start":"2026-05-04T01:49:06.911746Z","end":"2026-05-04T01:49:07.321078Z","steps":["trace[1953409064] 'agreement among raft nodes before linearized reading' (duration: 409.192459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:49:07.321129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:49:06.911736Z","time spent":"409.387335ms","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T01:49:14.885285Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3235} {"level":"info","ts":"2026-05-04T01:49:14.980089Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3235,"took":"93.061774ms","hash":3996522896,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":33579008,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-04T01:49:14.980192Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3996522896,"revision":3235,"compact-revision":-1} {"level":"warn","ts":"2026-05-04T01:51:02.229443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.428687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-05-04T01:51:02.229549Z","caller":"traceutil/trace.go:171","msg":"trace[2064809732] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:7591; }","duration":"470.569411ms","start":"2026-05-04T01:51:01.75895Z","end":"2026-05-04T01:51:02.229519Z","steps":["trace[2064809732] 'range keys from in-memory index tree' (duration: 470.328135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:02.229681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.787437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:51:02.229767Z","caller":"traceutil/trace.go:171","msg":"trace[31176113] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:0; response_revision:7591; }","duration":"470.931891ms","start":"2026-05-04T01:51:01.758817Z","end":"2026-05-04T01:51:02.229749Z","steps":["trace[31176113] 'range keys from in-memory index tree' (duration: 470.725646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:02.229812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:01.758799Z","time spent":"471.004313ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/barbican-ks-user\" "} {"level":"warn","ts":"2026-05-04T01:51:02.230376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:01.758932Z","time spent":"470.663744ms","remote":"127.0.0.1:46642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2014,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-05-04T01:51:02.23047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.556826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:51:02.230597Z","caller":"traceutil/trace.go:171","msg":"trace[58428602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7591; }","duration":"464.761852ms","start":"2026-05-04T01:51:01.765803Z","end":"2026-05-04T01:51:02.230564Z","steps":["trace[58428602] 'range keys from in-memory index tree' (duration: 464.477743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:02.230668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:01.765788Z","time spent":"464.865394ms","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-04T01:51:02.230709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.62986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:1 size:12704"} {"level":"info","ts":"2026-05-04T01:51:02.230792Z","caller":"traceutil/trace.go:171","msg":"trace[2064741212] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:1; response_revision:7591; }","duration":"471.735004ms","start":"2026-05-04T01:51:01.759039Z","end":"2026-05-04T01:51:02.230774Z","steps":["trace[2064741212] 'range keys from in-memory index tree' (duration: 471.275601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:02.230835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:01.759034Z","time spent":"471.793035ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":12728,"request content":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" "} {"level":"info","ts":"2026-05-04T01:51:02.23747Z","caller":"traceutil/trace.go:171","msg":"trace[1864554327] transaction","detail":"{read_only:false; response_revision:7592; number_of_response:1; }","duration":"438.414806ms","start":"2026-05-04T01:51:01.799036Z","end":"2026-05-04T01:51:02.237451Z","steps":["trace[1864554327] 'process raft request' (duration: 438.270912ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:51:02.237513Z","caller":"traceutil/trace.go:171","msg":"trace[129110455] linearizableReadLoop","detail":"{readStateIndex:7929; appliedIndex:7929; }","duration":"372.364828ms","start":"2026-05-04T01:51:01.865115Z","end":"2026-05-04T01:51:02.237479Z","steps":["trace[129110455] 'read index received' (duration: 372.349898ms)","trace[129110455] 'applied index is now lower than readState.Index' (duration: 11.94µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:51:02.237581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:01.799004Z","time spent":"438.513799ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12689,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:51:02.237912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.670757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-04T01:51:02.237967Z","caller":"traceutil/trace.go:171","msg":"trace[1720515058] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:7592; }","duration":"372.856892ms","start":"2026-05-04T01:51:01.865094Z","end":"2026-05-04T01:51:02.23795Z","steps":["trace[1720515058] 'agreement among raft nodes before linearized reading' (duration: 372.529863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:02.238012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:01.865083Z","time spent":"372.918574ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"info","ts":"2026-05-04T01:51:02.238333Z","caller":"traceutil/trace.go:171","msg":"trace[2132615322] transaction","detail":"{read_only:false; response_revision:7593; number_of_response:1; }","duration":"161.864724ms","start":"2026-05-04T01:51:02.076455Z","end":"2026-05-04T01:51:02.23832Z","steps":["trace[2132615322] 'process raft request' (duration: 161.475444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:06.828417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.481297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T01:51:06.829289Z","caller":"traceutil/trace.go:171","msg":"trace[676704535] transaction","detail":"{read_only:false; response_revision:7623; number_of_response:1; }","duration":"266.279289ms","start":"2026-05-04T01:51:06.56296Z","end":"2026-05-04T01:51:06.829239Z","steps":["trace[676704535] 'store kv pair into bolt db' {req_type:put; key:/registry/configmaps/openstack/messaging-topology-operator-leader-election; req_size:641; } (duration: 261.25138ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:51:06.829653Z","caller":"traceutil/trace.go:171","msg":"trace[478870398] transaction","detail":"{read_only:false; response_revision:7624; number_of_response:1; }","duration":"266.502565ms","start":"2026-05-04T01:51:06.56313Z","end":"2026-05-04T01:51:06.829632Z","steps":["trace[478870398] 'process raft request' (duration: 265.564739ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:51:09.508231Z","caller":"traceutil/trace.go:171","msg":"trace[863617294] transaction","detail":"{read_only:false; response_revision:7635; number_of_response:1; }","duration":"660.801846ms","start":"2026-05-04T01:51:08.8474Z","end":"2026-05-04T01:51:09.508202Z","steps":["trace[863617294] 'process raft request' (duration: 660.55831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:09.509009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:08.847384Z","time spent":"660.945361ms","remote":"127.0.0.1:46552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:51:09.511995Z","caller":"traceutil/trace.go:171","msg":"trace[955513808] transaction","detail":"{read_only:false; response_revision:7636; number_of_response:1; }","duration":"470.51788ms","start":"2026-05-04T01:51:09.041452Z","end":"2026-05-04T01:51:09.51197Z","steps":["trace[955513808] 'process raft request' (duration: 470.207262ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:51:09.512003Z","caller":"traceutil/trace.go:171","msg":"trace[2004616499] linearizableReadLoop","detail":"{readStateIndex:7978; appliedIndex:7975; }","duration":"466.491949ms","start":"2026-05-04T01:51:09.04548Z","end":"2026-05-04T01:51:09.511972Z","steps":["trace[2004616499] 'read index received' (duration: 462.775227ms)","trace[2004616499] 'applied index is now lower than readState.Index' (duration: 3.715622ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:51:09.512121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:09.041421Z","time spent":"470.625493ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:51:09.512144Z","caller":"traceutil/trace.go:171","msg":"trace[167180466] transaction","detail":"{read_only:false; response_revision:7638; number_of_response:1; }","duration":"467.837447ms","start":"2026-05-04T01:51:09.044294Z","end":"2026-05-04T01:51:09.512132Z","steps":["trace[167180466] 'process raft request' (duration: 467.634401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:09.513465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:09.044276Z","time spent":"469.022609ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:51:09.512164Z","caller":"traceutil/trace.go:171","msg":"trace[894884360] transaction","detail":"{read_only:false; response_revision:7637; number_of_response:1; }","duration":"470.445838ms","start":"2026-05-04T01:51:09.041678Z","end":"2026-05-04T01:51:09.512123Z","steps":["trace[894884360] 'process raft request' (duration: 470.159661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:09.513815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:09.041669Z","time spent":"472.054022ms","remote":"127.0.0.1:46552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:51:09.51224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.725815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:51:09.513972Z","caller":"traceutil/trace.go:171","msg":"trace[1589019381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7638; }","duration":"468.493654ms","start":"2026-05-04T01:51:09.045433Z","end":"2026-05-04T01:51:09.513927Z","steps":["trace[1589019381] 'agreement among raft nodes before linearized reading' (duration: 466.737756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:09.514054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:09.045412Z","time spent":"468.602387ms","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T01:51:49.133391Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002109.wal"} {"level":"info","ts":"2026-05-04T01:51:55.870108Z","caller":"traceutil/trace.go:171","msg":"trace[905913547] transaction","detail":"{read_only:false; response_revision:8139; number_of_response:1; }","duration":"182.544608ms","start":"2026-05-04T01:51:55.687548Z","end":"2026-05-04T01:51:55.870093Z","steps":["trace[905913547] 'process raft request' (duration: 182.448565ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:51:55.870211Z","caller":"traceutil/trace.go:171","msg":"trace[408124880] linearizableReadLoop","detail":"{readStateIndex:8499; appliedIndex:8499; }","duration":"120.5464ms","start":"2026-05-04T01:51:55.74964Z","end":"2026-05-04T01:51:55.870186Z","steps":["trace[408124880] 'read index received' (duration: 120.536149ms)","trace[408124880] 'applied index is now lower than readState.Index' (duration: 7.951µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:51:55.870463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.805677ms","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-05-04T01:51:55.870522Z","caller":"traceutil/trace.go:171","msg":"trace[299072823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8139; }","duration":"120.91797ms","start":"2026-05-04T01:51:55.749589Z","end":"2026-05-04T01:51:55.870507Z","steps":["trace[299072823] 'agreement among raft nodes before linearized reading' (duration: 120.706525ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:51:55.987298Z","caller":"traceutil/trace.go:171","msg":"trace[1095752294] linearizableReadLoop","detail":"{readStateIndex:8500; appliedIndex:8499; }","duration":"117.003712ms","start":"2026-05-04T01:51:55.870271Z","end":"2026-05-04T01:51:55.987274Z","steps":["trace[1095752294] 'read index received' (duration: 103.620054ms)","trace[1095752294] 'applied index is now lower than readState.Index' (duration: 13.381568ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:51:55.987371Z","caller":"traceutil/trace.go:171","msg":"trace[239288089] transaction","detail":"{read_only:false; response_revision:8140; number_of_response:1; }","duration":"125.578468ms","start":"2026-05-04T01:51:55.861775Z","end":"2026-05-04T01:51:55.987353Z","steps":["trace[239288089] 'process raft request' (duration: 112.099047ms)","trace[239288089] 'compare' (duration: 13.175583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:51:55.987463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.705939ms","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-05-04T01:51:55.987502Z","caller":"traceutil/trace.go:171","msg":"trace[605974314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8140; }","duration":"171.798221ms","start":"2026-05-04T01:51:55.815695Z","end":"2026-05-04T01:51:55.987493Z","steps":["trace[605974314] 'agreement among raft nodes before linearized reading' (duration: 171.682748ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:51:55.989848Z","caller":"traceutil/trace.go:171","msg":"trace[1446319686] transaction","detail":"{read_only:false; response_revision:8141; number_of_response:1; }","duration":"115.81115ms","start":"2026-05-04T01:51:55.87398Z","end":"2026-05-04T01:51:55.989791Z","steps":["trace[1446319686] 'process raft request' (duration: 115.577163ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:51:55.990014Z","caller":"traceutil/trace.go:171","msg":"trace[735138153] transaction","detail":"{read_only:false; response_revision:8142; number_of_response:1; }","duration":"101.274049ms","start":"2026-05-04T01:51:55.888724Z","end":"2026-05-04T01:51:55.989998Z","steps":["trace[735138153] 'process raft request' (duration: 100.95378ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:04.741809Z","caller":"traceutil/trace.go:171","msg":"trace[445159257] transaction","detail":"{read_only:false; response_revision:8204; number_of_response:1; }","duration":"187.830314ms","start":"2026-05-04T01:52:04.553953Z","end":"2026-05-04T01:52:04.741783Z","steps":["trace[445159257] 'process raft request' (duration: 187.669719ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:04.90748Z","caller":"traceutil/trace.go:171","msg":"trace[232575553] transaction","detail":"{read_only:false; response_revision:8205; number_of_response:1; }","duration":"353.180867ms","start":"2026-05-04T01:52:04.554268Z","end":"2026-05-04T01:52:04.907449Z","steps":["trace[232575553] 'process raft request' (duration: 270.727606ms)","trace[232575553] 'compare' (duration: 82.05954ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:52:04.907647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:52:04.554249Z","time spent":"353.323352ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:52:04.907685Z","caller":"traceutil/trace.go:171","msg":"trace[2118818722] transaction","detail":"{read_only:false; response_revision:8206; number_of_response:1; }","duration":"352.117668ms","start":"2026-05-04T01:52:04.555549Z","end":"2026-05-04T01:52:04.907667Z","steps":["trace[2118818722] 'process raft request' (duration: 351.852701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:04.9078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:52:04.555534Z","time spent":"352.20429ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:52:04.907869Z","caller":"traceutil/trace.go:171","msg":"trace[825908667] linearizableReadLoop","detail":"{readStateIndex:8572; appliedIndex:8569; }","duration":"291.545309ms","start":"2026-05-04T01:52:04.61631Z","end":"2026-05-04T01:52:04.907856Z","steps":["trace[825908667] 'read index received' (duration: 125.481416ms)","trace[825908667] 'applied index is now lower than readState.Index' (duration: 166.063003ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:52:04.90791Z","caller":"traceutil/trace.go:171","msg":"trace[920689884] transaction","detail":"{read_only:false; response_revision:8207; number_of_response:1; }","duration":"352.288392ms","start":"2026-05-04T01:52:04.555603Z","end":"2026-05-04T01:52:04.907891Z","steps":["trace[920689884] 'process raft request' (duration: 352.011445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:04.907977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:52:04.555586Z","time spent":"352.364695ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:52:04.907993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.614482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:52:04.908031Z","caller":"traceutil/trace.go:171","msg":"trace[151570997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8207; }","duration":"291.690784ms","start":"2026-05-04T01:52:04.616328Z","end":"2026-05-04T01:52:04.908019Z","steps":["trace[151570997] 'agreement among raft nodes before linearized reading' (duration: 291.631502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:04.908066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.737985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q46n6\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-04T01:52:04.908165Z","caller":"traceutil/trace.go:171","msg":"trace[1358542669] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q46n6; range_end:; response_count:1; response_revision:8207; }","duration":"291.873568ms","start":"2026-05-04T01:52:04.616274Z","end":"2026-05-04T01:52:04.908148Z","steps":["trace[1358542669] 'agreement among raft nodes before linearized reading' (duration: 291.653512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:32.626023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.205584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T01:52:32.626128Z","caller":"traceutil/trace.go:171","msg":"trace[1503668123] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"306.290745ms","start":"2026-05-04T01:52:32.319823Z","end":"2026-05-04T01:52:32.626114Z","steps":["trace[1503668123] 'process raft request' (duration: 36.930617ms)","trace[1503668123] 'compare' (duration: 268.913826ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:52:32.626182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:52:32.319796Z","time spent":"306.358367ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:52:32.629142Z","caller":"traceutil/trace.go:171","msg":"trace[1608722026] linearizableReadLoop","detail":"{readStateIndex:8816; appliedIndex:8815; }","duration":"192.721718ms","start":"2026-05-04T01:52:32.436385Z","end":"2026-05-04T01:52:32.629106Z","steps":["trace[1608722026] 'read index received' (duration: 192.489501ms)","trace[1608722026] 'applied index is now lower than readState.Index' (duration: 231.117µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:52:32.629284Z","caller":"traceutil/trace.go:171","msg":"trace[86566404] transaction","detail":"{read_only:false; response_revision:8440; number_of_response:1; }","duration":"195.935776ms","start":"2026-05-04T01:52:32.433338Z","end":"2026-05-04T01:52:32.629274Z","steps":["trace[86566404] 'process raft request' (duration: 195.613297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:32.629473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.071118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-04T01:52:32.629595Z","caller":"traceutil/trace.go:171","msg":"trace[1089395873] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8440; }","duration":"193.198811ms","start":"2026-05-04T01:52:32.436368Z","end":"2026-05-04T01:52:32.629566Z","steps":["trace[1089395873] 'agreement among raft nodes before linearized reading' (duration: 192.927054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:32.630174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.831185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-04T01:52:32.630248Z","caller":"traceutil/trace.go:171","msg":"trace[842545422] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8440; }","duration":"190.924259ms","start":"2026-05-04T01:52:32.439304Z","end":"2026-05-04T01:52:32.630229Z","steps":["trace[842545422] 'agreement among raft nodes before linearized reading' (duration: 190.762694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:32.630695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.180572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T01:52:32.630762Z","caller":"traceutil/trace.go:171","msg":"trace[1604213352] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:8440; }","duration":"107.382168ms","start":"2026-05-04T01:52:32.523364Z","end":"2026-05-04T01:52:32.630746Z","steps":["trace[1604213352] 'agreement among raft nodes before linearized reading' (duration: 107.135521ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:32.754662Z","caller":"traceutil/trace.go:171","msg":"trace[354573348] transaction","detail":"{read_only:false; response_revision:8441; number_of_response:1; }","duration":"118.531615ms","start":"2026-05-04T01:52:32.636097Z","end":"2026-05-04T01:52:32.754629Z","steps":["trace[354573348] 'process raft request' (duration: 118.234216ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:32.757252Z","caller":"traceutil/trace.go:171","msg":"trace[1557186495] transaction","detail":"{read_only:false; response_revision:8442; number_of_response:1; }","duration":"121.073584ms","start":"2026-05-04T01:52:32.636159Z","end":"2026-05-04T01:52:32.757232Z","steps":["trace[1557186495] 'process raft request' (duration: 120.855588ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:46.257153Z","caller":"traceutil/trace.go:171","msg":"trace[74048462] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"105.124855ms","start":"2026-05-04T01:52:46.152011Z","end":"2026-05-04T01:52:46.257136Z","steps":["trace[74048462] 'process raft request' (duration: 105.009991ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:47.146796Z","caller":"traceutil/trace.go:171","msg":"trace[692208547] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"239.951629ms","start":"2026-05-04T01:52:46.906823Z","end":"2026-05-04T01:52:47.146775Z","steps":["trace[692208547] 'process raft request' (duration: 239.794235ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:47.25141Z","caller":"traceutil/trace.go:171","msg":"trace[1732990850] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"344.223891ms","start":"2026-05-04T01:52:46.907156Z","end":"2026-05-04T01:52:47.25138Z","steps":["trace[1732990850] 'process raft request' (duration: 326.39274ms)","trace[1732990850] 'compare' (duration: 17.615415ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:52:47.25158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:52:46.907149Z","time spent":"344.348864ms","remote":"127.0.0.1:46552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:52:47.25157Z","caller":"traceutil/trace.go:171","msg":"trace[1546989493] linearizableReadLoop","detail":"{readStateIndex:8964; appliedIndex:8962; }","duration":"322.642706ms","start":"2026-05-04T01:52:46.928885Z","end":"2026-05-04T01:52:47.251528Z","steps":["trace[1546989493] 'read index received' (duration: 217.802349ms)","trace[1546989493] 'applied index is now lower than readState.Index' (duration: 104.836737ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:52:47.251613Z","caller":"traceutil/trace.go:171","msg":"trace[1179586642] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"278.477899ms","start":"2026-05-04T01:52:46.973095Z","end":"2026-05-04T01:52:47.251573Z","steps":["trace[1179586642] 'process raft request' (duration: 278.196242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:47.251926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.039258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-04T01:52:47.251912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.303295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-769979b459-tc7b5.18ac39cd564992de\" ","response":"range_response_count:1 size:1134"} {"level":"warn","ts":"2026-05-04T01:52:47.251978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.67439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"warn","ts":"2026-05-04T01:52:47.251978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.441639ms","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-05-04T01:52:47.252004Z","caller":"traceutil/trace.go:171","msg":"trace[39878310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8580; }","duration":"323.13221ms","start":"2026-05-04T01:52:46.92885Z","end":"2026-05-04T01:52:47.251982Z","steps":["trace[39878310] 'agreement among raft nodes before linearized reading' (duration: 322.876483ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:47.252021Z","caller":"traceutil/trace.go:171","msg":"trace[1825073892] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8580; }","duration":"101.768723ms","start":"2026-05-04T01:52:47.150239Z","end":"2026-05-04T01:52:47.252007Z","steps":["trace[1825073892] 'agreement among raft nodes before linearized reading' (duration: 101.606129ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:47.25201Z","caller":"traceutil/trace.go:171","msg":"trace[461710122] range","detail":"{range_begin:/registry/events/openstack/barbican-api-769979b459-tc7b5.18ac39cd564992de; range_end:; response_count:1; response_revision:8580; }","duration":"266.435918ms","start":"2026-05-04T01:52:46.985555Z","end":"2026-05-04T01:52:47.251991Z","steps":["trace[461710122] 'agreement among raft nodes before linearized reading' (duration: 266.217112ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:47.252064Z","caller":"traceutil/trace.go:171","msg":"trace[62179076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8580; }","duration":"107.568022ms","start":"2026-05-04T01:52:47.144472Z","end":"2026-05-04T01:52:47.25204Z","steps":["trace[62179076] 'agreement among raft nodes before linearized reading' (duration: 107.314605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:47.252119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:52:46.928837Z","time spent":"323.258234ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-04T01:52:47.252458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.007788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-05-04T01:52:47.252501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.798465ms","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-05-04T01:52:47.252518Z","caller":"traceutil/trace.go:171","msg":"trace[1209544055] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8580; }","duration":"123.07004ms","start":"2026-05-04T01:52:47.129433Z","end":"2026-05-04T01:52:47.252503Z","steps":["trace[1209544055] 'agreement among raft nodes before linearized reading' (duration: 122.896255ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:47.252561Z","caller":"traceutil/trace.go:171","msg":"trace[1093750409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8580; }","duration":"121.921978ms","start":"2026-05-04T01:52:47.130624Z","end":"2026-05-04T01:52:47.252546Z","steps":["trace[1093750409] 'agreement among raft nodes before linearized reading' (duration: 121.729823ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:54:14.890778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6571} {"level":"info","ts":"2026-05-04T01:54:14.986289Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6571,"took":"93.579998ms","hash":2835005642,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":26816512,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-04T01:54:14.986359Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2835005642,"revision":6571,"compact-revision":3235} {"level":"info","ts":"2026-05-04T01:54:24.852502Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c6cf68dde442ffa5","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-04T01:54:24.857574Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-04T01:54:24.857678Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-04T01:59:14.897675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9503} {"level":"info","ts":"2026-05-04T01:59:14.980206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9503,"took":"79.916052ms","hash":2552526044,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":28860416,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-04T01:59:14.980284Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2552526044,"revision":9503,"compact-revision":6571} {"level":"info","ts":"2026-05-04T02:04:14.904223Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12352} {"level":"info","ts":"2026-05-04T02:04:14.996679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12352,"took":"90.533636ms","hash":2772401628,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":24940544,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-04T02:04:14.996768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2772401628,"revision":12352,"compact-revision":9503} {"level":"info","ts":"2026-05-04T02:09:14.911684Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14711} {"level":"info","ts":"2026-05-04T02:09:15.001778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14711,"took":"87.553923ms","hash":2843704441,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":22466560,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-04T02:09:15.001858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2843704441,"revision":14711,"compact-revision":12352} {"level":"warn","ts":"2026-05-04T02:10:45.315538Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303507693352449,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-04T02:10:45.461156Z","caller":"traceutil/trace.go:171","msg":"trace[1820344303] transaction","detail":"{read_only:false; response_revision:17262; number_of_response:1; }","duration":"646.988612ms","start":"2026-05-04T02:10:44.814136Z","end":"2026-05-04T02:10:45.461125Z","steps":["trace[1820344303] 'process raft request' (duration: 646.774757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:10:45.461356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:10:44.814122Z","time spent":"647.113955ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T02:10:45.465276Z","caller":"traceutil/trace.go:171","msg":"trace[1265210713] linearizableReadLoop","detail":"{readStateIndex:18115; appliedIndex:18111; }","duration":"650.560361ms","start":"2026-05-04T02:10:44.814687Z","end":"2026-05-04T02:10:45.465247Z","steps":["trace[1265210713] 'read index received' (duration: 646.260753ms)","trace[1265210713] 'applied index is now lower than readState.Index' (duration: 4.298378ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T02:10:45.46534Z","caller":"traceutil/trace.go:171","msg":"trace[18048399] transaction","detail":"{read_only:false; response_revision:17264; number_of_response:1; }","duration":"650.547391ms","start":"2026-05-04T02:10:44.814763Z","end":"2026-05-04T02:10:45.46531Z","steps":["trace[18048399] 'process raft request' (duration: 650.406117ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:10:45.465398Z","caller":"traceutil/trace.go:171","msg":"trace[576790764] transaction","detail":"{read_only:false; response_revision:17263; number_of_response:1; }","duration":"650.614923ms","start":"2026-05-04T02:10:44.814757Z","end":"2026-05-04T02:10:45.465372Z","steps":["trace[576790764] 'process raft request' (duration: 650.283393ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:10:45.465481Z","caller":"traceutil/trace.go:171","msg":"trace[2004903924] transaction","detail":"{read_only:false; response_revision:17265; number_of_response:1; }","duration":"650.062417ms","start":"2026-05-04T02:10:44.815371Z","end":"2026-05-04T02:10:45.465434Z","steps":["trace[2004903924] 'process raft request' (duration: 649.838311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:10:45.465492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:10:44.814738Z","time spent":"650.681204ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T02:10:45.465525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:10:44.814742Z","time spent":"650.714995ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T02:10:45.465538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.300253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T02:10:45.465584Z","caller":"traceutil/trace.go:171","msg":"trace[729463154] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:17265; }","duration":"191.388535ms","start":"2026-05-04T02:10:45.274184Z","end":"2026-05-04T02:10:45.465572Z","steps":["trace[729463154] 'agreement among raft nodes before linearized reading' (duration: 191.310193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:10:45.465634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:10:44.815353Z","time spent":"650.17014ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T02:10:45.465743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.028134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T02:10:45.465891Z","caller":"traceutil/trace.go:171","msg":"trace[1073920775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17265; }","duration":"651.187738ms","start":"2026-05-04T02:10:44.81468Z","end":"2026-05-04T02:10:45.465868Z","steps":["trace[1073920775] 'agreement among raft nodes before linearized reading' (duration: 650.89833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:10:45.466006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:10:44.814622Z","time spent":"651.368434ms","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T02:14:14.918401Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16679} {"level":"info","ts":"2026-05-04T02:14:14.987623Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16679,"took":"66.817021ms","hash":2744564940,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":23977984,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T02:14:14.987691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2744564940,"revision":16679,"compact-revision":14711} {"level":"info","ts":"2026-05-04T02:15:19.387435Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c6cf68dde442ffa5","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-04T02:15:19.391651Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-04T02:15:19.391809Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-04T02:19:14.924722Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18623} {"level":"info","ts":"2026-05-04T02:19:14.994028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18623,"took":"66.872993ms","hash":840713306,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":23908352,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T02:19:14.99408Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":840713306,"revision":18623,"compact-revision":16679} {"level":"info","ts":"2026-05-04T02:24:14.931624Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20557} {"level":"info","ts":"2026-05-04T02:24:14.996521Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20557,"took":"63.186961ms","hash":1199554558,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":24010752,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T02:24:14.99658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1199554558,"revision":20557,"compact-revision":18623} {"level":"info","ts":"2026-05-04T02:24:45.475174Z","caller":"traceutil/trace.go:171","msg":"trace[1692498006] transaction","detail":"{read_only:false; response_revision:22693; number_of_response:1; }","duration":"624.090831ms","start":"2026-05-04T02:24:44.851044Z","end":"2026-05-04T02:24:45.475135Z","steps":["trace[1692498006] 'process raft request' (duration: 623.388432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:45.475399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:44.851022Z","time spent":"624.270816ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T02:24:48.902938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303507693368580,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T02:24:49.403622Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303507693368580,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T02:24:49.904021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303507693368580,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T02:24:50.404612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303507693368580,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T02:24:50.546084Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.240569575s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-04T02:24:50.569683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.3053Z","time spent":"2.264377891s","remote":"127.0.0.1:46500","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-04T02:24:50.615012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000455507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-04T02:24:50.615152Z","caller":"traceutil/trace.go:171","msg":"trace[386765884] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000660433s","start":"2026-05-04T02:24:48.614454Z","end":"2026-05-04T02:24:50.615114Z","steps":["trace[386765884] 'agreement among raft nodes before linearized reading' (duration: 2.000485988s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.615229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.614437Z","time spent":"2.000774806s","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T02:24:50.989487Z","caller":"traceutil/trace.go:171","msg":"trace[462170725] linearizableReadLoop","detail":"{readStateIndex:23908; appliedIndex:23905; }","duration":"2.587419585s","start":"2026-05-04T02:24:48.402047Z","end":"2026-05-04T02:24:50.989467Z","steps":["trace[462170725] 'read index received' (duration: 2.167869351s)","trace[462170725] 'applied index is now lower than readState.Index' (duration: 419.549234ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T02:24:50.989693Z","caller":"traceutil/trace.go:171","msg":"trace[497969294] transaction","detail":"{read_only:false; response_revision:22715; number_of_response:1; }","duration":"484.691528ms","start":"2026-05-04T02:24:50.504994Z","end":"2026-05-04T02:24:50.989685Z","steps":["trace[497969294] 'process raft request' (duration: 484.444761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.989781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:50.504962Z","time spent":"484.75313ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T02:24:50.98977Z","caller":"traceutil/trace.go:171","msg":"trace[1872430751] transaction","detail":"{read_only:false; response_revision:22714; number_of_response:1; }","duration":"819.641711ms","start":"2026-05-04T02:24:50.170076Z","end":"2026-05-04T02:24:50.989718Z","steps":["trace[1872430751] 'process raft request' (duration: 819.334432ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:24:50.989818Z","caller":"traceutil/trace.go:171","msg":"trace[852358346] transaction","detail":"{read_only:false; response_revision:22713; number_of_response:1; }","duration":"1.537261661s","start":"2026-05-04T02:24:49.452525Z","end":"2026-05-04T02:24:50.989786Z","steps":["trace[852358346] 'process raft request' (duration: 1.536850479s)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:24:50.98979Z","caller":"traceutil/trace.go:171","msg":"trace[1246524603] transaction","detail":"{read_only:false; response_revision:22712; number_of_response:1; }","duration":"2.181824016s","start":"2026-05-04T02:24:48.807934Z","end":"2026-05-04T02:24:50.989758Z","steps":["trace[1246524603] 'process raft request' (duration: 2.18124807s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.989946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.452506Z","time spent":"1.537366083s","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T02:24:50.989951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:50.170055Z","time spent":"819.809565ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T02:24:50.990027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.807912Z","time spent":"2.182018922s","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T02:24:50.990105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.588060302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q46n6\" ","response":"range_response_count:1 size:6521"} {"level":"info","ts":"2026-05-04T02:24:50.990175Z","caller":"traceutil/trace.go:171","msg":"trace[19143250] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q46n6; range_end:; response_count:1; response_revision:22715; }","duration":"2.588162565s","start":"2026-05-04T02:24:48.401997Z","end":"2026-05-04T02:24:50.99016Z","steps":["trace[19143250] 'agreement among raft nodes before linearized reading' (duration: 2.587817396s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.990203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.401979Z","time spent":"2.588217697s","remote":"127.0.0.1:46640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6545,"request content":"key:\"/registry/pods/openstack/keepalived-q46n6\" "} {"level":"warn","ts":"2026-05-04T02:24:50.990697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.327649475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T02:24:50.990784Z","caller":"traceutil/trace.go:171","msg":"trace[403895365] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:22716; }","duration":"2.327777159s","start":"2026-05-04T02:24:48.662992Z","end":"2026-05-04T02:24:50.990769Z","steps":["trace[403895365] 'agreement among raft nodes before linearized reading' (duration: 2.327650515s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.990829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.662972Z","time spent":"2.32784723s","remote":"127.0.0.1:56176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-04T02:24:50.990888Z","caller":"traceutil/trace.go:171","msg":"trace[1256875749] transaction","detail":"{read_only:false; response_revision:22716; number_of_response:1; }","duration":"420.100259ms","start":"2026-05-04T02:24:50.570773Z","end":"2026-05-04T02:24:50.990873Z","steps":["trace[1256875749] 'process raft request' (duration: 419.80325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.990976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:50.570752Z","time spent":"420.18364ms","remote":"127.0.0.1:46500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T02:24:50.991255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.696472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T02:24:50.991315Z","caller":"traceutil/trace.go:171","msg":"trace[1109528979] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:22716; }","duration":"171.811515ms","start":"2026-05-04T02:24:50.819489Z","end":"2026-05-04T02:24:50.9913Z","steps":["trace[1109528979] 'agreement among raft nodes before linearized reading' (duration: 171.717103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.99161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182380639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-04T02:24:50.991641Z","caller":"traceutil/trace.go:171","msg":"trace[1171523551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22716; }","duration":"1.18242488s","start":"2026-05-04T02:24:49.809206Z","end":"2026-05-04T02:24:50.991631Z","steps":["trace[1171523551] 'agreement among raft nodes before linearized reading' (duration: 1.182313076s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.99167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.809201Z","time spent":"1.18246258s","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-04T02:24:50.991638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182347737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-04T02:24:50.991773Z","caller":"traceutil/trace.go:171","msg":"trace[891465173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22716; }","duration":"1.182510323s","start":"2026-05-04T02:24:49.809232Z","end":"2026-05-04T02:24:50.991742Z","steps":["trace[891465173] 'agreement among raft nodes before linearized reading' (duration: 1.182122042s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.99183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.809229Z","time spent":"1.182589395s","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-04T02:24:50.990829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204819377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"warn","ts":"2026-05-04T02:24:50.991866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.889591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-04T02:24:50.991956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407210056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-04T02:24:50.991921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.116884116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"warn","ts":"2026-05-04T02:24:50.991981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.160370285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T02:24:50.992051Z","caller":"traceutil/trace.go:171","msg":"trace[1069654275] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:22716; }","duration":"2.160474547s","start":"2026-05-04T02:24:48.831567Z","end":"2026-05-04T02:24:50.992041Z","steps":["trace[1069654275] 'agreement among raft nodes before linearized reading' (duration: 2.160373984s)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:24:50.992065Z","caller":"traceutil/trace.go:171","msg":"trace[1684361402] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22716; }","duration":"2.117073881s","start":"2026-05-04T02:24:48.874949Z","end":"2026-05-04T02:24:50.992023Z","steps":["trace[1684361402] 'agreement among raft nodes before linearized reading' (duration: 2.116709601s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.992087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.491687814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"warn","ts":"2026-05-04T02:24:50.992089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.831552Z","time spent":"2.160524049s","remote":"127.0.0.1:56028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-05-04T02:24:50.992116Z","caller":"traceutil/trace.go:171","msg":"trace[159647515] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:22716; }","duration":"1.491738786s","start":"2026-05-04T02:24:49.500369Z","end":"2026-05-04T02:24:50.992108Z","steps":["trace[159647515] 'agreement among raft nodes before linearized reading' (duration: 1.491660304s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.992138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.500361Z","time spent":"1.491770966s","remote":"127.0.0.1:46552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":686,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-04T02:24:50.992136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.874935Z","time spent":"2.117182184s","remote":"127.0.0.1:46552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":671,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-04T02:24:50.992157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391369609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-04T02:24:50.99198Z","caller":"traceutil/trace.go:171","msg":"trace[670159765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22716; }","duration":"376.24475ms","start":"2026-05-04T02:24:50.615705Z","end":"2026-05-04T02:24:50.99195Z","steps":["trace[670159765] 'agreement among raft nodes before linearized reading' (duration: 376.001044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.992215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.337042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-04T02:24:50.992264Z","caller":"traceutil/trace.go:171","msg":"trace[1486518527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22716; }","duration":"815.411624ms","start":"2026-05-04T02:24:50.176841Z","end":"2026-05-04T02:24:50.992253Z","steps":["trace[1486518527] 'agreement among raft nodes before linearized reading' (duration: 815.28921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.992284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:50.176825Z","time spent":"815.452985ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-04T02:24:50.992259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:50.615683Z","time spent":"376.551608ms","remote":"127.0.0.1:46462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T02:24:50.991915Z","caller":"traceutil/trace.go:171","msg":"trace[846955035] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22716; }","duration":"1.205923387s","start":"2026-05-04T02:24:49.785979Z","end":"2026-05-04T02:24:50.991902Z","steps":["trace[846955035] 'agreement among raft nodes before linearized reading' (duration: 1.204677123s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.992357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.785973Z","time spent":"1.206375179s","remote":"127.0.0.1:46640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11521,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-04T02:24:50.992383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364002065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-05-04T02:24:50.992007Z","caller":"traceutil/trace.go:171","msg":"trace[339892083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22716; }","duration":"1.407248437s","start":"2026-05-04T02:24:49.584737Z","end":"2026-05-04T02:24:50.991985Z","steps":["trace[339892083] 'agreement among raft nodes before linearized reading' (duration: 1.407134944s)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:24:50.992422Z","caller":"traceutil/trace.go:171","msg":"trace[1881024586] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:22716; }","duration":"1.364054187s","start":"2026-05-04T02:24:49.628357Z","end":"2026-05-04T02:24:50.992411Z","steps":["trace[1881024586] 'agreement among raft nodes before linearized reading' (duration: 1.36271279s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.992453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.584703Z","time spent":"1.407742581s","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-04T02:24:50.992459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.628352Z","time spent":"1.364100648s","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":551,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-05-04T02:24:50.992194Z","caller":"traceutil/trace.go:171","msg":"trace[1958975634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22716; }","duration":"1.391413811s","start":"2026-05-04T02:24:49.60077Z","end":"2026-05-04T02:24:50.992184Z","steps":["trace[1958975634] 'agreement among raft nodes before linearized reading' (duration: 1.391250586s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.992659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.600766Z","time spent":"1.391878533s","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-04T02:25:29.86089Z","caller":"traceutil/trace.go:171","msg":"trace[608520355] transaction","detail":"{read_only:false; response_revision:23003; number_of_response:1; }","duration":"104.82696ms","start":"2026-05-04T02:25:29.756027Z","end":"2026-05-04T02:25:29.860854Z","steps":["trace[608520355] 'process raft request' (duration: 104.610634ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:29:14.939585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22498} {"level":"info","ts":"2026-05-04T02:29:15.00698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22498,"took":"64.914319ms","hash":4246927171,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":24006656,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T02:29:15.007042Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4246927171,"revision":22498,"compact-revision":20557} {"level":"info","ts":"2026-05-04T02:32:52.715Z","caller":"traceutil/trace.go:171","msg":"trace[764947361] transaction","detail":"{read_only:false; response_revision:25862; number_of_response:1; }","duration":"127.831142ms","start":"2026-05-04T02:32:52.587143Z","end":"2026-05-04T02:32:52.714974Z","steps":["trace[764947361] 'process raft request' (duration: 127.660078ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:32:52.715627Z","caller":"traceutil/trace.go:171","msg":"trace[368291862] transaction","detail":"{read_only:false; response_revision:25863; number_of_response:1; }","duration":"121.700574ms","start":"2026-05-04T02:32:52.593903Z","end":"2026-05-04T02:32:52.715603Z","steps":["trace[368291862] 'process raft request' (duration: 121.551509ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:34:14.947314Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24455} {"level":"info","ts":"2026-05-04T02:34:15.024707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24455,"took":"75.010936ms","hash":376508457,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":24129536,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T02:34:15.024788Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":376508457,"revision":24455,"compact-revision":22498} {"level":"info","ts":"2026-05-04T02:36:33.366468Z","caller":"traceutil/trace.go:171","msg":"trace[1879351944] transaction","detail":"{read_only:false; response_revision:27288; number_of_response:1; }","duration":"131.882132ms","start":"2026-05-04T02:36:33.234555Z","end":"2026-05-04T02:36:33.366437Z","steps":["trace[1879351944] 'process raft request' (duration: 131.708247ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:39:14.957001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26397} {"level":"info","ts":"2026-05-04T02:39:15.029627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26397,"took":"69.851753ms","hash":1631800197,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":24031232,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T02:39:15.029717Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1631800197,"revision":26397,"compact-revision":24455} {"level":"info","ts":"2026-05-04T02:39:32.654185Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c6cf68dde442ffa5","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-04T02:39:32.658554Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-04T02:39:32.658735Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-04T02:44:14.963748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28331} {"level":"info","ts":"2026-05-04T02:44:15.03552Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28331,"took":"68.706912ms","hash":2711219498,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":24154112,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T02:44:15.035574Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2711219498,"revision":28331,"compact-revision":26397}