{"level":"warn","ts":"2026-04-28T03:19:34.196716Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T03:19:34.196788Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.222:2380","--initial-cluster=instance=https://199.19.213.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.222: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-04-28T03:19:34.196842Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T03:19:34.196855Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-04-28T03:19:34.196869Z","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-04-28T03:19:34.197538Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"]} {"level":"info","ts":"2026-04-28T03:19:34.197612Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.222: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-04-28T03:19:34.203631Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.808918ms"} {"level":"info","ts":"2026-04-28T03:19:34.214307Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b78f8b1ca6063edc","cluster-id":"eff609df5818f5a9"} {"level":"info","ts":"2026-04-28T03:19:34.21438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T03:19:34.214401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 0"} {"level":"info","ts":"2026-04-28T03:19:34.214411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b78f8b1ca6063edc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T03:19:34.214426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 1"} {"level":"info","ts":"2026-04-28T03:19:34.21446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"warn","ts":"2026-04-28T03:19:34.223588Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T03:19:34.226382Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T03:19:34.227441Z","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-04-28T03:19:34.230986Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b78f8b1ca6063edc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T03:19:34.234459Z","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-04-28T03:19:34.234527Z","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-04-28T03:19:34.234542Z","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-04-28T03:19:34.235036Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b78f8b1ca6063edc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T03:19:34.236033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"info","ts":"2026-04-28T03:19:34.236137Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","added-peer-id":"b78f8b1ca6063edc","added-peer-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-04-28T03:19:34.236411Z","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-04-28T03:19:34.236639Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b78f8b1ca6063edc","initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T03:19:34.236693Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T03:19:34.236745Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-04-28T03:19:34.236769Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-04-28T03:19:35.114834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T03:19:35.114881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T03:19:35.114907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgPreVoteResp from b78f8b1ca6063edc at term 1"} {"level":"info","ts":"2026-04-28T03:19:35.114921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became candidate at term 2"} {"level":"info","ts":"2026-04-28T03:19:35.114928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgVoteResp from b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-04-28T03:19:35.114939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became leader at term 2"} {"level":"info","ts":"2026-04-28T03:19:35.114948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b78f8b1ca6063edc elected leader b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-04-28T03:19:35.11599Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b78f8b1ca6063edc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.222:2379]}","request-path":"/0/members/b78f8b1ca6063edc/attributes","cluster-id":"eff609df5818f5a9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T03:19:35.115982Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:19:35.116111Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:19:35.116172Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:19:35.116431Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T03:19:35.116454Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T03:19:35.117796Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:19:35.117966Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:19:35.118151Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:19:35.118864Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T03:19:35.119426Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.222:2379"} {"level":"info","ts":"2026-04-28T03:20:04.960316Z","caller":"traceutil/trace.go:171","msg":"trace[586469099] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"104.957044ms","start":"2026-04-28T03:20:04.855338Z","end":"2026-04-28T03:20:04.960296Z","steps":["trace[586469099] 'process raft request' (duration: 104.845983ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:04.968314Z","caller":"traceutil/trace.go:171","msg":"trace[334674171] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"110.577826ms","start":"2026-04-28T03:20:04.857711Z","end":"2026-04-28T03:20:04.968288Z","steps":["trace[334674171] 'process raft request' (duration: 110.223833ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:04.968385Z","caller":"traceutil/trace.go:171","msg":"trace[1336311163] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"101.800006ms","start":"2026-04-28T03:20:04.866565Z","end":"2026-04-28T03:20:04.968364Z","steps":["trace[1336311163] 'process raft request' (duration: 101.452212ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:06.27336Z","caller":"traceutil/trace.go:171","msg":"trace[1044922153] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"352.668404ms","start":"2026-04-28T03:20:05.92066Z","end":"2026-04-28T03:20:06.273328Z","steps":["trace[1044922153] 'process raft request' (duration: 352.440422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:06.274303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:05.920647Z","time spent":"352.802346ms","remote":"127.0.0.1:38118","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-04-28T03:20:06.300043Z","caller":"traceutil/trace.go:171","msg":"trace[998910793] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"280.602542ms","start":"2026-04-28T03:20:06.019396Z","end":"2026-04-28T03:20:06.299999Z","steps":["trace[998910793] 'process raft request' (duration: 280.200029ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:06.300734Z","caller":"traceutil/trace.go:171","msg":"trace[2119130485] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"374.739803ms","start":"2026-04-28T03:20:05.925963Z","end":"2026-04-28T03:20:06.300702Z","steps":["trace[2119130485] 'process raft request' (duration: 373.252379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:06.30088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:05.925926Z","time spent":"374.864614ms","remote":"127.0.0.1:37884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:20:10.952157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.60218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qbcmq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-28T03:20:10.952285Z","caller":"traceutil/trace.go:171","msg":"trace[2138517938] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qbcmq; range_end:; response_count:1; response_revision:612; }","duration":"112.776552ms","start":"2026-04-28T03:20:10.839489Z","end":"2026-04-28T03:20:10.952266Z","steps":["trace[2138517938] 'range keys from in-memory index tree' (duration: 112.446659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:10.952157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.339201ms","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-04-28T03:20:10.952564Z","caller":"traceutil/trace.go:171","msg":"trace[1246858117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"126.812864ms","start":"2026-04-28T03:20:10.825735Z","end":"2026-04-28T03:20:10.952548Z","steps":["trace[1246858117] 'range keys from in-memory index tree' (duration: 126.162239ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:11.005909Z","caller":"traceutil/trace.go:171","msg":"trace[1619957103] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"121.419687ms","start":"2026-04-28T03:20:10.884466Z","end":"2026-04-28T03:20:11.005886Z","steps":["trace[1619957103] 'process raft request' (duration: 120.831702ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:11.681559Z","caller":"traceutil/trace.go:171","msg":"trace[872146588] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"259.888673ms","start":"2026-04-28T03:20:11.421632Z","end":"2026-04-28T03:20:11.681521Z","steps":["trace[872146588] 'read index received' (duration: 254.860879ms)","trace[872146588] 'applied index is now lower than readState.Index' (duration: 5.026254ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:11.681606Z","caller":"traceutil/trace.go:171","msg":"trace[1641512410] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"603.768482ms","start":"2026-04-28T03:20:11.077812Z","end":"2026-04-28T03:20:11.68158Z","steps":["trace[1641512410] 'process raft request' (duration: 598.710928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:11.681773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.118815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:20:11.681816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:20:11.077789Z","time spent":"603.912293ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:20:11.681859Z","caller":"traceutil/trace.go:171","msg":"trace[123442174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"260.258747ms","start":"2026-04-28T03:20:11.421577Z","end":"2026-04-28T03:20:11.681835Z","steps":["trace[123442174] 'agreement among raft nodes before linearized reading' (duration: 260.075425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:11.682148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.90284ms","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-04-28T03:20:11.682295Z","caller":"traceutil/trace.go:171","msg":"trace[1552763233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"175.064482ms","start":"2026-04-28T03:20:11.507211Z","end":"2026-04-28T03:20:11.682276Z","steps":["trace[1552763233] 'agreement among raft nodes before linearized reading' (duration: 174.731849ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:40.432939Z","caller":"traceutil/trace.go:171","msg":"trace[749582830] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"287.78281ms","start":"2026-04-28T03:21:40.145134Z","end":"2026-04-28T03:21:40.432917Z","steps":["trace[749582830] 'process raft request' (duration: 287.657537ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:40.598493Z","caller":"traceutil/trace.go:171","msg":"trace[504422597] linearizableReadLoop","detail":"{readStateIndex:1879; appliedIndex:1877; }","duration":"349.31945ms","start":"2026-04-28T03:21:40.249152Z","end":"2026-04-28T03:21:40.598472Z","steps":["trace[504422597] 'read index received' (duration: 183.776331ms)","trace[504422597] 'applied index is now lower than readState.Index' (duration: 165.542359ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:40.598602Z","caller":"traceutil/trace.go:171","msg":"trace[2126264047] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"451.269652ms","start":"2026-04-28T03:21:40.147307Z","end":"2026-04-28T03:21:40.598577Z","steps":["trace[2126264047] 'process raft request' (duration: 427.620728ms)","trace[2126264047] 'compare' (duration: 23.215944ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:40.598698Z","caller":"traceutil/trace.go:171","msg":"trace[2057673603] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"349.658827ms","start":"2026-04-28T03:21:40.249013Z","end":"2026-04-28T03:21:40.598672Z","steps":["trace[2057673603] 'process raft request' (duration: 349.386901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:40.598798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:40.147294Z","time spent":"451.374224ms","remote":"127.0.0.1:37938","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-04-28T03:21:40.598879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:40.248982Z","time spent":"349.79328ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:21:40.598891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.709128ms","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-04-28T03:21:40.598985Z","caller":"traceutil/trace.go:171","msg":"trace[1546120780] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1825; }","duration":"350.559017ms","start":"2026-04-28T03:21:40.248413Z","end":"2026-04-28T03:21:40.598972Z","steps":["trace[1546120780] 'agreement among raft nodes before linearized reading' (duration: 350.288461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:40.59905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:40.248395Z","time spent":"350.64248ms","remote":"127.0.0.1:58664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4615,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-28T03:21:40.599228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.26869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d101de7-3916-4438-96d4-51b7365d3e40\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-28T03:21:40.599902Z","caller":"traceutil/trace.go:171","msg":"trace[1140723657] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d101de7-3916-4438-96d4-51b7365d3e40; range_end:; response_count:1; response_revision:1825; }","duration":"133.943855ms","start":"2026-04-28T03:21:40.465914Z","end":"2026-04-28T03:21:40.599858Z","steps":["trace[1140723657] 'agreement among raft nodes before linearized reading' (duration: 133.202428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:40.599235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.302825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d101de7-3916-4438-96d4-51b7365d3e40\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-28T03:21:40.600066Z","caller":"traceutil/trace.go:171","msg":"trace[2045684242] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d101de7-3916-4438-96d4-51b7365d3e40; range_end:; response_count:1; response_revision:1825; }","duration":"271.153204ms","start":"2026-04-28T03:21:40.328891Z","end":"2026-04-28T03:21:40.600044Z","steps":["trace[2045684242] 'agreement among raft nodes before linearized reading' (duration: 270.219683ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:40.765627Z","caller":"traceutil/trace.go:171","msg":"trace[1858918411] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"158.108831ms","start":"2026-04-28T03:21:40.607494Z","end":"2026-04-28T03:21:40.765603Z","steps":["trace[1858918411] 'process raft request' (duration: 158.048089ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:40.765749Z","caller":"traceutil/trace.go:171","msg":"trace[1899946308] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"158.174323ms","start":"2026-04-28T03:21:40.607479Z","end":"2026-04-28T03:21:40.765654Z","steps":["trace[1899946308] 'process raft request' (duration: 124.214546ms)","trace[1899946308] 'compare' (duration: 33.742112ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:17.891872Z","caller":"traceutil/trace.go:171","msg":"trace[1794936842] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"126.237269ms","start":"2026-04-28T03:22:17.765612Z","end":"2026-04-28T03:22:17.891849Z","steps":["trace[1794936842] 'process raft request' (duration: 125.959194ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:23:04.220913Z","caller":"traceutil/trace.go:171","msg":"trace[134672370] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"107.107045ms","start":"2026-04-28T03:23:04.113761Z","end":"2026-04-28T03:23:04.220868Z","steps":["trace[134672370] 'process raft request' (duration: 106.879421ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:23:32.347437Z","caller":"traceutil/trace.go:171","msg":"trace[1310721767] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"184.444103ms","start":"2026-04-28T03:23:32.16295Z","end":"2026-04-28T03:23:32.347394Z","steps":["trace[1310721767] 'process raft request' (duration: 184.186379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:32.565658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.792154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:23:32.565769Z","caller":"traceutil/trace.go:171","msg":"trace[68843588] linearizableReadLoop","detail":"{readStateIndex:2848; appliedIndex:2847; }","duration":"163.23093ms","start":"2026-04-28T03:23:32.402521Z","end":"2026-04-28T03:23:32.565752Z","steps":["trace[68843588] 'read index received' (duration: 36.96949ms)","trace[68843588] 'applied index is now lower than readState.Index' (duration: 126.26018ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:23:32.565826Z","caller":"traceutil/trace.go:171","msg":"trace[1496715309] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"213.628214ms","start":"2026-04-28T03:23:32.352154Z","end":"2026-04-28T03:23:32.565782Z","steps":["trace[1496715309] 'process raft request' (duration: 87.498486ms)","trace[1496715309] 'compare' (duration: 125.629521ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:23:32.565947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.407692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T03:23:32.566022Z","caller":"traceutil/trace.go:171","msg":"trace[1986381146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2745; }","duration":"163.534404ms","start":"2026-04-28T03:23:32.402471Z","end":"2026-04-28T03:23:32.566006Z","steps":["trace[1986381146] 'agreement among raft nodes before linearized reading' (duration: 163.350062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:32.566144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.487299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-28T03:23:32.566533Z","caller":"traceutil/trace.go:171","msg":"trace[1110307869] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2745; }","duration":"139.109997ms","start":"2026-04-28T03:23:32.4274Z","end":"2026-04-28T03:23:32.56651Z","steps":["trace[1110307869] 'agreement among raft nodes before linearized reading' (duration: 138.445478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:32.566911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.36923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18aa674f1d34d86f\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-04-28T03:23:32.567223Z","caller":"traceutil/trace.go:171","msg":"trace[1907718812] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18aa674f1d34d86f; range_end:; response_count:1; response_revision:2745; }","duration":"123.699725ms","start":"2026-04-28T03:23:32.443503Z","end":"2026-04-28T03:23:32.567202Z","steps":["trace[1907718812] 'agreement among raft nodes before linearized reading' (duration: 122.406097ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:23:32.782887Z","caller":"traceutil/trace.go:171","msg":"trace[40572556] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"211.266313ms","start":"2026-04-28T03:23:32.57158Z","end":"2026-04-28T03:23:32.782846Z","steps":["trace[40572556] 'process raft request' (duration: 118.826288ms)","trace[40572556] 'compare' (duration: 92.165811ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:23:32.783214Z","caller":"traceutil/trace.go:171","msg":"trace[1853591870] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"210.667124ms","start":"2026-04-28T03:23:32.572511Z","end":"2026-04-28T03:23:32.783178Z","steps":["trace[1853591870] 'process raft request' (duration: 210.276289ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:00.150419Z","caller":"traceutil/trace.go:171","msg":"trace[336148981] transaction","detail":"{read_only:false; response_revision:4444; number_of_response:1; }","duration":"117.226253ms","start":"2026-04-28T03:26:00.033157Z","end":"2026-04-28T03:26:00.150383Z","steps":["trace[336148981] 'process raft request' (duration: 116.986815ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:04.549329Z","caller":"traceutil/trace.go:171","msg":"trace[1349345383] linearizableReadLoop","detail":"{readStateIndex:4727; appliedIndex:4726; }","duration":"279.356256ms","start":"2026-04-28T03:26:04.269947Z","end":"2026-04-28T03:26:04.549304Z","steps":["trace[1349345383] 'read index received' (duration: 278.656135ms)","trace[1349345383] 'applied index is now lower than readState.Index' (duration: 698.681µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:04.549775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.485954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:26:04.549894Z","caller":"traceutil/trace.go:171","msg":"trace[1823693920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4533; }","duration":"127.690901ms","start":"2026-04-28T03:26:04.422173Z","end":"2026-04-28T03:26:04.549864Z","steps":["trace[1823693920] 'agreement among raft nodes before linearized reading' (duration: 127.496295ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:04.550134Z","caller":"traceutil/trace.go:171","msg":"trace[1781037257] transaction","detail":"{read_only:false; response_revision:4533; number_of_response:1; }","duration":"336.371149ms","start":"2026-04-28T03:26:04.213055Z","end":"2026-04-28T03:26:04.549426Z","steps":["trace[1781037257] 'process raft request' (duration: 335.613436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:04.550428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:04.213035Z","time spent":"337.235187ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:26:04.550704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.713789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c4501d73-b22a-4cdd-a90a-1d893a17d780\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-28T03:26:04.550758Z","caller":"traceutil/trace.go:171","msg":"trace[1798121398] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c4501d73-b22a-4cdd-a90a-1d893a17d780; range_end:; response_count:1; response_revision:4533; }","duration":"280.822622ms","start":"2026-04-28T03:26:04.269922Z","end":"2026-04-28T03:26:04.550744Z","steps":["trace[1798121398] 'agreement among raft nodes before linearized reading' (duration: 279.529562ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:18.660712Z","caller":"traceutil/trace.go:171","msg":"trace[1508008766] linearizableReadLoop","detail":"{readStateIndex:4928; appliedIndex:4927; }","duration":"351.579461ms","start":"2026-04-28T03:26:18.309095Z","end":"2026-04-28T03:26:18.660675Z","steps":["trace[1508008766] 'read index received' (duration: 346.166981ms)","trace[1508008766] 'applied index is now lower than readState.Index' (duration: 5.41034ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:18.660951Z","caller":"traceutil/trace.go:171","msg":"trace[1630671988] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"353.274492ms","start":"2026-04-28T03:26:18.30766Z","end":"2026-04-28T03:26:18.660935Z","steps":["trace[1630671988] 'process raft request' (duration: 347.674997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:18.661092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:18.307638Z","time spent":"353.354454ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:26:18.66117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.385112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:26:18.661267Z","caller":"traceutil/trace.go:171","msg":"trace[853386827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4726; }","duration":"239.541816ms","start":"2026-04-28T03:26:18.421701Z","end":"2026-04-28T03:26:18.661243Z","steps":["trace[853386827] 'agreement among raft nodes before linearized reading' (duration: 239.391651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:18.661408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.305282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-28T03:26:18.661447Z","caller":"traceutil/trace.go:171","msg":"trace[1547489013] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:4726; }","duration":"352.378355ms","start":"2026-04-28T03:26:18.309057Z","end":"2026-04-28T03:26:18.661436Z","steps":["trace[1547489013] 'agreement among raft nodes before linearized reading' (duration: 352.293582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:18.661471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:18.309041Z","time spent":"352.421096ms","remote":"127.0.0.1:38120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-04-28T03:26:18.841577Z","caller":"traceutil/trace.go:171","msg":"trace[1563799265] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"175.086105ms","start":"2026-04-28T03:26:18.666459Z","end":"2026-04-28T03:26:18.841545Z","steps":["trace[1563799265] 'process raft request' (duration: 141.182885ms)","trace[1563799265] 'compare' (duration: 33.372914ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:19.09706Z","caller":"traceutil/trace.go:171","msg":"trace[1863072520] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"175.776085ms","start":"2026-04-28T03:26:18.921254Z","end":"2026-04-28T03:26:19.09703Z","steps":["trace[1863072520] 'process raft request' (duration: 77.994561ms)","trace[1863072520] 'compare' (duration: 96.96071ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:19.097296Z","caller":"traceutil/trace.go:171","msg":"trace[899461058] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"175.539408ms","start":"2026-04-28T03:26:18.921739Z","end":"2026-04-28T03:26:19.097278Z","steps":["trace[899461058] 'process raft request' (duration: 175.220948ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:19.097485Z","caller":"traceutil/trace.go:171","msg":"trace[1219256171] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"174.066635ms","start":"2026-04-28T03:26:18.923385Z","end":"2026-04-28T03:26:19.097451Z","steps":["trace[1219256171] 'process raft request' (duration: 173.987853ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:19.097701Z","caller":"traceutil/trace.go:171","msg":"trace[1280497622] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"175.444545ms","start":"2026-04-28T03:26:18.922249Z","end":"2026-04-28T03:26:19.097693Z","steps":["trace[1280497622] 'process raft request' (duration: 174.987972ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:07.954356Z","caller":"traceutil/trace.go:171","msg":"trace[1558073541] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"103.164131ms","start":"2026-04-28T03:27:07.851153Z","end":"2026-04-28T03:27:07.954317Z","steps":["trace[1558073541] 'process raft request' (duration: 65.731221ms)","trace[1558073541] 'compare' (duration: 36.775854ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:08.275601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.716857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:27:08.275913Z","caller":"traceutil/trace.go:171","msg":"trace[1563439667] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"316.309764ms","start":"2026-04-28T03:27:07.959558Z","end":"2026-04-28T03:27:08.275867Z","steps":["trace[1563439667] 'process raft request' (duration: 97.205378ms)","trace[1563439667] 'compare' (duration: 218.448329ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:08.276126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:07.959521Z","time spent":"316.494548ms","remote":"127.0.0.1:38118","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-04-28T03:27:08.276103Z","caller":"traceutil/trace.go:171","msg":"trace[246102456] linearizableReadLoop","detail":"{readStateIndex:5459; appliedIndex:5458; }","duration":"255.075607ms","start":"2026-04-28T03:27:08.020926Z","end":"2026-04-28T03:27:08.276001Z","steps":["trace[246102456] 'read index received' (duration: 35.554821ms)","trace[246102456] 'applied index is now lower than readState.Index' (duration: 219.518296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:08.276362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.398295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:27:08.276671Z","caller":"traceutil/trace.go:171","msg":"trace[1056842036] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:5236; }","duration":"255.866616ms","start":"2026-04-28T03:27:08.02078Z","end":"2026-04-28T03:27:08.276647Z","steps":["trace[1056842036] 'agreement among raft nodes before linearized reading' (duration: 255.431865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:08.642589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.670104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:27:08.64279Z","caller":"traceutil/trace.go:171","msg":"trace[113069698] linearizableReadLoop","detail":"{readStateIndex:5460; appliedIndex:5459; }","duration":"366.517339ms","start":"2026-04-28T03:27:08.276253Z","end":"2026-04-28T03:27:08.64277Z","steps":["trace[113069698] 'read index received' (duration: 195.531737ms)","trace[113069698] 'applied index is now lower than readState.Index' (duration: 170.983952ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:08.642897Z","caller":"traceutil/trace.go:171","msg":"trace[1927601870] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"452.079671ms","start":"2026-04-28T03:27:08.190806Z","end":"2026-04-28T03:27:08.642886Z","steps":["trace[1927601870] 'process raft request' (duration: 280.995106ms)","trace[1927601870] 'compare' (duration: 170.304445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:08.642949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.190762Z","time spent":"452.161363ms","remote":"127.0.0.1:38086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:08.643168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.021776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T03:27:08.643196Z","caller":"traceutil/trace.go:171","msg":"trace[1022810860] range","detail":"{range_begin:/registry/masterleases/199.19.213.222; range_end:; response_count:1; response_revision:5237; }","duration":"596.070836ms","start":"2026-04-28T03:27:08.047116Z","end":"2026-04-28T03:27:08.643186Z","steps":["trace[1022810860] 'agreement among raft nodes before linearized reading' (duration: 595.937883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:08.643198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.980547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-04-28T03:27:08.643218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.0471Z","time spent":"596.113208ms","remote":"127.0.0.1:37866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.222\" "} {"level":"warn","ts":"2026-04-28T03:27:08.643242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.643573ms","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-04-28T03:27:08.643394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.722118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:27:08.643423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.315988ms","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-04-28T03:27:08.643325Z","caller":"traceutil/trace.go:171","msg":"trace[1804877914] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5237; }","duration":"551.162061ms","start":"2026-04-28T03:27:08.092151Z","end":"2026-04-28T03:27:08.643314Z","steps":["trace[1804877914] 'agreement among raft nodes before linearized reading' (duration: 550.929606ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:08.64349Z","caller":"traceutil/trace.go:171","msg":"trace[213597037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5237; }","duration":"222.894451ms","start":"2026-04-28T03:27:08.420577Z","end":"2026-04-28T03:27:08.643471Z","steps":["trace[213597037] 'agreement among raft nodes before linearized reading' (duration: 222.745508ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:08.643449Z","caller":"traceutil/trace.go:171","msg":"trace[92110205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5237; }","duration":"519.805717ms","start":"2026-04-28T03:27:08.123559Z","end":"2026-04-28T03:27:08.643364Z","steps":["trace[92110205] 'agreement among raft nodes before linearized reading' (duration: 519.406557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:08.643535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.564212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-28T03:27:08.643502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.092122Z","time spent":"551.367377ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-04-28T03:27:08.643497Z","caller":"traceutil/trace.go:171","msg":"trace[163788470] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5237; }","duration":"123.439632ms","start":"2026-04-28T03:27:08.520041Z","end":"2026-04-28T03:27:08.64348Z","steps":["trace[163788470] 'agreement among raft nodes before linearized reading' (duration: 123.278767ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:08.643648Z","caller":"traceutil/trace.go:171","msg":"trace[1297579342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5237; }","duration":"543.744016ms","start":"2026-04-28T03:27:08.099878Z","end":"2026-04-28T03:27:08.643622Z","steps":["trace[1297579342] 'agreement among raft nodes before linearized reading' (duration: 543.405708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:08.643709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.099852Z","time spent":"543.846448ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T03:27:08.643737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.123528Z","time spent":"520.073413ms","remote":"127.0.0.1:38118","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-04-28T03:27:08.986718Z","caller":"traceutil/trace.go:171","msg":"trace[78127232] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"336.834325ms","start":"2026-04-28T03:27:08.649847Z","end":"2026-04-28T03:27:08.986681Z","steps":["trace[78127232] 'process raft request' (duration: 328.144458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:08.986951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.64983Z","time spent":"336.99459ms","remote":"127.0.0.1:38118","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-04-28T03:27:08.98705Z","caller":"traceutil/trace.go:171","msg":"trace[975261295] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"335.994325ms","start":"2026-04-28T03:27:08.651018Z","end":"2026-04-28T03:27:08.987012Z","steps":["trace[975261295] 'process raft request' (duration: 335.82689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:08.987264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.65101Z","time spent":"336.136298ms","remote":"127.0.0.1:38118","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-04-28T03:27:08.987262Z","caller":"traceutil/trace.go:171","msg":"trace[1435320662] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"336.717183ms","start":"2026-04-28T03:27:08.650495Z","end":"2026-04-28T03:27:08.987212Z","steps":["trace[1435320662] 'process raft request' (duration: 336.081847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:08.987621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.650487Z","time spent":"336.994789ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:09.298443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.750979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:27:09.299004Z","caller":"traceutil/trace.go:171","msg":"trace[670341882] linearizableReadLoop","detail":"{readStateIndex:5467; appliedIndex:5461; }","duration":"494.82539ms","start":"2026-04-28T03:27:08.80415Z","end":"2026-04-28T03:27:09.298975Z","steps":["trace[670341882] 'read index received' (duration: 173.850374ms)","trace[670341882] 'applied index is now lower than readState.Index' (duration: 320.973726ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:09.299184Z","caller":"traceutil/trace.go:171","msg":"trace[1110994689] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"582.63894ms","start":"2026-04-28T03:27:08.716503Z","end":"2026-04-28T03:27:09.299142Z","steps":["trace[1110994689] 'process raft request' (duration: 402.013099ms)","trace[1110994689] 'compare' (duration: 179.540724ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:09.299259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.074686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:27:09.299328Z","caller":"traceutil/trace.go:171","msg":"trace[900084596] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5243; }","duration":"495.20311ms","start":"2026-04-28T03:27:08.804107Z","end":"2026-04-28T03:27:09.299311Z","steps":["trace[900084596] 'agreement among raft nodes before linearized reading' (duration: 495.003505ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:09.299321Z","caller":"traceutil/trace.go:171","msg":"trace[1925837778] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"527.927449ms","start":"2026-04-28T03:27:08.771355Z","end":"2026-04-28T03:27:09.299283Z","steps":["trace[1925837778] 'process raft request' (duration: 527.54695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.29938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.804095Z","time spent":"495.274901ms","remote":"127.0.0.1:38120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":38,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:27:09.299379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.716477Z","time spent":"582.799723ms","remote":"127.0.0.1:37866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:27:09.299338Z","caller":"traceutil/trace.go:171","msg":"trace[252879334] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"578.104235ms","start":"2026-04-28T03:27:08.721146Z","end":"2026-04-28T03:27:09.29925Z","steps":["trace[252879334] 'process raft request' (duration: 577.444759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.299515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.771337Z","time spent":"528.082223ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:09.299614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.809748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T03:27:09.299699Z","caller":"traceutil/trace.go:171","msg":"trace[1912865588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5243; }","duration":"239.972452ms","start":"2026-04-28T03:27:09.059711Z","end":"2026-04-28T03:27:09.299684Z","steps":["trace[1912865588] 'agreement among raft nodes before linearized reading' (duration: 239.408348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.299825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.716157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T03:27:09.30013Z","caller":"traceutil/trace.go:171","msg":"trace[1560318164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5243; }","duration":"188.032805ms","start":"2026-04-28T03:27:09.112055Z","end":"2026-04-28T03:27:09.300088Z","steps":["trace[1560318164] 'agreement among raft nodes before linearized reading' (duration: 187.151223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.300167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.721115Z","time spent":"578.433904ms","remote":"127.0.0.1:37938","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-04-28T03:27:09.630549Z","caller":"traceutil/trace.go:171","msg":"trace[616381860] linearizableReadLoop","detail":"{readStateIndex:5468; appliedIndex:5467; }","duration":"323.95148ms","start":"2026-04-28T03:27:09.306567Z","end":"2026-04-28T03:27:09.630519Z","steps":["trace[616381860] 'read index received' (duration: 247.198103ms)","trace[616381860] 'applied index is now lower than readState.Index' (duration: 76.752397ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:09.630609Z","caller":"traceutil/trace.go:171","msg":"trace[1881443285] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"324.915913ms","start":"2026-04-28T03:27:09.305665Z","end":"2026-04-28T03:27:09.630581Z","steps":["trace[1881443285] 'process raft request' (duration: 248.019833ms)","trace[1881443285] 'compare' (duration: 76.634684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:09.630735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.157564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-28T03:27:09.630768Z","caller":"traceutil/trace.go:171","msg":"trace[579885633] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:5244; }","duration":"324.242297ms","start":"2026-04-28T03:27:09.306517Z","end":"2026-04-28T03:27:09.63076Z","steps":["trace[579885633] 'agreement among raft nodes before linearized reading' (duration: 324.145504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.630794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.306496Z","time spent":"324.291378ms","remote":"127.0.0.1:38120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-04-28T03:27:09.631047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.305645Z","time spent":"325.018516ms","remote":"127.0.0.1:38118","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-04-28T03:27:09.720178Z","caller":"traceutil/trace.go:171","msg":"trace[212414883] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"412.365957ms","start":"2026-04-28T03:27:09.307788Z","end":"2026-04-28T03:27:09.720154Z","steps":["trace[212414883] 'process raft request' (duration: 412.176032ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:09.7202Z","caller":"traceutil/trace.go:171","msg":"trace[1716034437] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"413.413773ms","start":"2026-04-28T03:27:09.306727Z","end":"2026-04-28T03:27:09.720141Z","steps":["trace[1716034437] 'process raft request' (duration: 413.087565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.720248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.64317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:27:09.720308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.307781Z","time spent":"412.47159ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:09.720318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.876913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:27:09.7204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.63965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:09.720422Z","caller":"traceutil/trace.go:171","msg":"trace[1157582620] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5246; }","duration":"202.715372ms","start":"2026-04-28T03:27:09.517701Z","end":"2026-04-28T03:27:09.720416Z","steps":["trace[1157582620] 'agreement among raft nodes before linearized reading' (duration: 202.678861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.72044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.306702Z","time spent":"413.623669ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:09.720455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.894427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:27:09.720558Z","caller":"traceutil/trace.go:171","msg":"trace[601789678] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5246; }","duration":"342.40871ms","start":"2026-04-28T03:27:09.378136Z","end":"2026-04-28T03:27:09.720545Z","steps":["trace[601789678] 'agreement among raft nodes before linearized reading' (duration: 341.887057ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:09.720425Z","caller":"traceutil/trace.go:171","msg":"trace[1080823217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5246; }","duration":"299.070728ms","start":"2026-04-28T03:27:09.421334Z","end":"2026-04-28T03:27:09.720404Z","steps":["trace[1080823217] 'agreement among raft nodes before linearized reading' (duration: 298.850452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.720602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.378108Z","time spent":"342.482541ms","remote":"127.0.0.1:58630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-04-28T03:27:09.720351Z","caller":"traceutil/trace.go:171","msg":"trace[2017679652] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5246; }","duration":"202.801654ms","start":"2026-04-28T03:27:09.517518Z","end":"2026-04-28T03:27:09.72032Z","steps":["trace[2017679652] 'agreement among raft nodes before linearized reading' (duration: 202.602399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.72043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.189799ms","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-04-28T03:27:09.720814Z","caller":"traceutil/trace.go:171","msg":"trace[380056982] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5246; }","duration":"202.662721ms","start":"2026-04-28T03:27:09.518136Z","end":"2026-04-28T03:27:09.720799Z","steps":["trace[380056982] 'agreement among raft nodes before linearized reading' (duration: 201.952433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:10.279584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.157609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:27:10.279904Z","caller":"traceutil/trace.go:171","msg":"trace[730363319] linearizableReadLoop","detail":"{readStateIndex:5472; appliedIndex:5470; }","duration":"205.861915ms","start":"2026-04-28T03:27:10.074015Z","end":"2026-04-28T03:27:10.279877Z","steps":["trace[730363319] 'read index received' (duration: 51.051µs)","trace[730363319] 'applied index is now lower than readState.Index' (duration: 205.809344ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:10.280059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.04908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:27:10.280092Z","caller":"traceutil/trace.go:171","msg":"trace[631502663] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5248; }","duration":"206.139342ms","start":"2026-04-28T03:27:10.073942Z","end":"2026-04-28T03:27:10.280082Z","steps":["trace[631502663] 'agreement among raft nodes before linearized reading' (duration: 205.997519ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:10.280191Z","caller":"traceutil/trace.go:171","msg":"trace[1157916864] transaction","detail":"{read_only:false; response_revision:5248; number_of_response:1; }","duration":"319.480778ms","start":"2026-04-28T03:27:09.960665Z","end":"2026-04-28T03:27:10.280146Z","steps":["trace[1157916864] 'process raft request' (duration: 319.080018ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:10.280247Z","caller":"traceutil/trace.go:171","msg":"trace[447471292] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"551.713556ms","start":"2026-04-28T03:27:09.728486Z","end":"2026-04-28T03:27:10.280199Z","steps":["trace[447471292] 'process raft request' (duration: 203.789488ms)","trace[447471292] 'compare' (duration: 346.81316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:10.280428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.960611Z","time spent":"319.678252ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:10.280484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.728459Z","time spent":"551.891001ms","remote":"127.0.0.1:37938","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-04-28T03:27:10.624797Z","caller":"traceutil/trace.go:171","msg":"trace[774357505] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"338.530039ms","start":"2026-04-28T03:27:10.28623Z","end":"2026-04-28T03:27:10.62476Z","steps":["trace[774357505] 'process raft request' (duration: 338.337124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:10.624984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:10.286196Z","time spent":"338.690173ms","remote":"127.0.0.1:38118","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-04-28T03:27:10.796447Z","caller":"traceutil/trace.go:171","msg":"trace[154868530] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"508.039229ms","start":"2026-04-28T03:27:10.288358Z","end":"2026-04-28T03:27:10.796398Z","steps":["trace[154868530] 'process raft request' (duration: 424.967581ms)","trace[154868530] 'compare' (duration: 82.72529ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:10.79662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:10.288342Z","time spent":"508.188463ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:29:35.142853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3258} {"level":"info","ts":"2026-04-28T03:29:35.236192Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3258,"took":"91.53412ms","hash":1309928545,"current-db-size-bytes":58933248,"current-db-size":"59 MB","current-db-size-in-use-bytes":32362496,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-28T03:29:35.236279Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1309928545,"revision":3258,"compact-revision":-1}