{"level":"warn","ts":"2026-07-28T19:28:39.909924Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T19:28:39.910003Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.86: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.86:2380","--initial-cluster=instance=https://199.19.213.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.86: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-07-28T19:28:39.910065Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T19:28:39.910072Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-07-28T19:28:39.910094Z","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-07-28T19:28:39.910717Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"]} {"level":"info","ts":"2026-07-28T19:28:39.910788Z","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.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.86: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-07-28T19:28:39.915653Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.684176ms"} {"level":"info","ts":"2026-07-28T19:28:39.922921Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"849a6ffb5e81c109","cluster-id":"d4d86b1dd8829199"} {"level":"info","ts":"2026-07-28T19:28:39.92304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=()"} {"level":"info","ts":"2026-07-28T19:28:39.92308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 0"} {"level":"info","ts":"2026-07-28T19:28:39.923121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 849a6ffb5e81c109 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-28T19:28:39.923149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 1"} {"level":"info","ts":"2026-07-28T19:28:39.923212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"warn","ts":"2026-07-28T19:28:39.932197Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-28T19:28:39.935317Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-28T19:28:39.936486Z","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-07-28T19:28:39.938547Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"849a6ffb5e81c109","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-28T19:28:39.938805Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"849a6ffb5e81c109","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-28T19:28:39.939084Z","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-07-28T19:28:39.939166Z","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-07-28T19:28:39.939183Z","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-07-28T19:28:39.941277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"info","ts":"2026-07-28T19:28:39.94148Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","added-peer-id":"849a6ffb5e81c109","added-peer-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-07-28T19:28:39.94636Z","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-07-28T19:28:39.946496Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-07-28T19:28:39.947678Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-07-28T19:28:39.947997Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"849a6ffb5e81c109","initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-28T19:28:39.94803Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-28T19:28:40.123825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 is starting a new election at term 1"} {"level":"info","ts":"2026-07-28T19:28:40.123878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-28T19:28:40.123911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgPreVoteResp from 849a6ffb5e81c109 at term 1"} {"level":"info","ts":"2026-07-28T19:28:40.123929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became candidate at term 2"} {"level":"info","ts":"2026-07-28T19:28:40.12394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgVoteResp from 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-07-28T19:28:40.123956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became leader at term 2"} {"level":"info","ts":"2026-07-28T19:28:40.123969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 849a6ffb5e81c109 elected leader 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-07-28T19:28:40.125034Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"849a6ffb5e81c109","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.86:2379]}","request-path":"/0/members/849a6ffb5e81c109/attributes","cluster-id":"d4d86b1dd8829199","publish-timeout":"7s"} {"level":"info","ts":"2026-07-28T19:28:40.125053Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T19:28:40.125227Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T19:28:40.125446Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T19:28:40.125783Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-28T19:28:40.125825Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-28T19:28:40.128137Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.86:2379"} {"level":"info","ts":"2026-07-28T19:28:40.12821Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T19:28:40.128267Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T19:28:40.128289Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T19:28:40.129769Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-28T19:29:08.845639Z","caller":"traceutil/trace.go:171","msg":"trace[1865294687] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"422.823238ms","start":"2026-07-28T19:29:08.422784Z","end":"2026-07-28T19:29:08.845607Z","steps":["trace[1865294687] 'process raft request' (duration: 422.644382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:08.846307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:08.422775Z","time spent":"422.973062ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:29:08.849039Z","caller":"traceutil/trace.go:171","msg":"trace[1294997363] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:444; }","duration":"153.537224ms","start":"2026-07-28T19:29:08.695436Z","end":"2026-07-28T19:29:08.848974Z","steps":["trace[1294997363] 'read index received' (duration: 150.061001ms)","trace[1294997363] 'applied index is now lower than readState.Index' (duration: 3.474403ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:29:08.849236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.761241ms","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-07-28T19:29:08.849321Z","caller":"traceutil/trace.go:171","msg":"trace[2037147727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:429; }","duration":"153.935076ms","start":"2026-07-28T19:29:08.695369Z","end":"2026-07-28T19:29:08.849304Z","steps":["trace[2037147727] 'agreement among raft nodes before linearized reading' (duration: 153.761551ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:12.425756Z","caller":"traceutil/trace.go:171","msg":"trace[242485143] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"128.034718ms","start":"2026-07-28T19:29:12.2977Z","end":"2026-07-28T19:29:12.425734Z","steps":["trace[242485143] 'process raft request' (duration: 127.883813ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:16.432055Z","caller":"traceutil/trace.go:171","msg":"trace[2070347947] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"346.400283ms","start":"2026-07-28T19:29:16.085629Z","end":"2026-07-28T19:29:16.432029Z","steps":["trace[2070347947] 'process raft request' (duration: 346.251208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.43222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:16.0856Z","time spent":"346.550217ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T19:29:16.442026Z","caller":"traceutil/trace.go:171","msg":"trace[1698425714] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"331.920901ms","start":"2026-07-28T19:29:16.109931Z","end":"2026-07-28T19:29:16.441852Z","steps":["trace[1698425714] 'process raft request' (duration: 330.983414ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:16.442002Z","caller":"traceutil/trace.go:171","msg":"trace[1600500694] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"339.423595ms","start":"2026-07-28T19:29:16.102468Z","end":"2026-07-28T19:29:16.441891Z","steps":["trace[1600500694] 'process raft request' (duration: 338.065655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.442393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:16.109887Z","time spent":"332.255081ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T19:29:16.442835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:16.102462Z","time spent":"339.741124ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T19:29:16.44299Z","caller":"traceutil/trace.go:171","msg":"trace[1988710017] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"335.780707ms","start":"2026-07-28T19:29:16.107069Z","end":"2026-07-28T19:29:16.44285Z","steps":["trace[1988710017] 'process raft request' (duration: 333.786687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.443941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:16.107031Z","time spent":"336.140167ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T19:29:16.444168Z","caller":"traceutil/trace.go:171","msg":"trace[1139975745] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"341.788435ms","start":"2026-07-28T19:29:16.101541Z","end":"2026-07-28T19:29:16.443329Z","steps":["trace[1139975745] 'process raft request' (duration: 338.93046ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:16.446675Z","caller":"traceutil/trace.go:171","msg":"trace[2143210459] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"344.559798ms","start":"2026-07-28T19:29:16.101425Z","end":"2026-07-28T19:29:16.445985Z","steps":["trace[2143210459] 'process raft request' (duration: 338.952421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.446703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:16.101537Z","time spent":"343.261519ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T19:29:16.446758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:16.101414Z","time spent":"345.305939ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T19:29:16.446972Z","caller":"traceutil/trace.go:171","msg":"trace[1059172934] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"354.254866ms","start":"2026-07-28T19:29:16.091714Z","end":"2026-07-28T19:29:16.445969Z","steps":["trace[1059172934] 'process raft request' (duration: 348.546017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.44705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:16.091664Z","time spent":"355.351649ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T19:29:16.447142Z","caller":"traceutil/trace.go:171","msg":"trace[219597042] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"354.232386ms","start":"2026-07-28T19:29:16.091755Z","end":"2026-07-28T19:29:16.445988Z","steps":["trace[219597042] 'process raft request' (duration: 348.587448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.447183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:16.091727Z","time spent":"355.438342ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T19:29:16.447241Z","caller":"traceutil/trace.go:171","msg":"trace[1905367587] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"331.207381ms","start":"2026-07-28T19:29:16.11474Z","end":"2026-07-28T19:29:16.445947Z","steps":["trace[1905367587] 'process raft request' (duration: 326.309815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.447277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:16.11472Z","time spent":"332.542269ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T19:29:16.447324Z","caller":"traceutil/trace.go:171","msg":"trace[573796106] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"312.902756ms","start":"2026-07-28T19:29:16.133058Z","end":"2026-07-28T19:29:16.445961Z","steps":["trace[573796106] 'process raft request' (duration: 308.073342ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:16.447412Z","caller":"traceutil/trace.go:171","msg":"trace[333085153] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"178.8983ms","start":"2026-07-28T19:29:16.2685Z","end":"2026-07-28T19:29:16.447398Z","steps":["trace[333085153] 'process raft request' (duration: 173.271883ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:16.447056Z","caller":"traceutil/trace.go:171","msg":"trace[190958841] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"339.925929ms","start":"2026-07-28T19:29:16.101474Z","end":"2026-07-28T19:29:16.4414Z","steps":["trace[190958841] 'process raft request' (duration: 338.958331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.447546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:16.133036Z","time spent":"314.38894ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T19:29:16.447738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:16.101469Z","time spent":"346.128844ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T19:29:23.044823Z","caller":"traceutil/trace.go:171","msg":"trace[735546513] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"488.236533ms","start":"2026-07-28T19:29:22.556546Z","end":"2026-07-28T19:29:23.044782Z","steps":["trace[735546513] 'process raft request' (duration: 487.366779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:23.045087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:22.556504Z","time spent":"488.418538ms","remote":"127.0.0.1:38194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:29:23.392783Z","caller":"traceutil/trace.go:171","msg":"trace[2040146926] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"471.319496ms","start":"2026-07-28T19:29:22.921434Z","end":"2026-07-28T19:29:23.392754Z","steps":["trace[2040146926] 'read index received' (duration: 122.09584ms)","trace[2040146926] 'applied index is now lower than readState.Index' (duration: 349.222636ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:29:23.392872Z","caller":"traceutil/trace.go:171","msg":"trace[1060971009] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"819.323972ms","start":"2026-07-28T19:29:22.573512Z","end":"2026-07-28T19:29:23.392836Z","steps":["trace[1060971009] 'process raft request' (duration: 819.074495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:23.393048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.573632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-28T19:29:23.393063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:22.573487Z","time spent":"819.457856ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:29:23.393087Z","caller":"traceutil/trace.go:171","msg":"trace[1411268559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"471.662315ms","start":"2026-07-28T19:29:22.921412Z","end":"2026-07-28T19:29:23.393074Z","steps":["trace[1411268559] 'agreement among raft nodes before linearized reading' (duration: 471.47026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:23.393125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:22.921307Z","time spent":"471.809359ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-28T19:29:23.403553Z","caller":"traceutil/trace.go:171","msg":"trace[902154042] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"292.940734ms","start":"2026-07-28T19:29:23.110587Z","end":"2026-07-28T19:29:23.403528Z","steps":["trace[902154042] 'process raft request' (duration: 292.660846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:23.403662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.789658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-28T19:29:23.403839Z","caller":"traceutil/trace.go:171","msg":"trace[1404613679] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:588; }","duration":"134.991344ms","start":"2026-07-28T19:29:23.268802Z","end":"2026-07-28T19:29:23.403794Z","steps":["trace[1404613679] 'agreement among raft nodes before linearized reading' (duration: 134.663114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:23.40369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.56774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T19:29:23.403969Z","caller":"traceutil/trace.go:171","msg":"trace[1075933085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"475.89575ms","start":"2026-07-28T19:29:22.928039Z","end":"2026-07-28T19:29:23.403935Z","steps":["trace[1075933085] 'agreement among raft nodes before linearized reading' (duration: 475.392485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:23.403752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.861997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7hx6s\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-28T19:29:23.404061Z","caller":"traceutil/trace.go:171","msg":"trace[1759728495] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7hx6s; range_end:; response_count:1; response_revision:588; }","duration":"335.261989ms","start":"2026-07-28T19:29:23.068785Z","end":"2026-07-28T19:29:23.404047Z","steps":["trace[1759728495] 'agreement among raft nodes before linearized reading' (duration: 334.810026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:23.404061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:22.928027Z","time spent":"476.012423ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T19:29:23.404099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:23.068766Z","time spent":"335.32389ms","remote":"127.0.0.1:38278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-7hx6s\" "} {"level":"warn","ts":"2026-07-28T19:29:23.405664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.201808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:29:23.405723Z","caller":"traceutil/trace.go:171","msg":"trace[213703057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"140.291151ms","start":"2026-07-28T19:29:23.265418Z","end":"2026-07-28T19:29:23.405709Z","steps":["trace[213703057] 'agreement among raft nodes before linearized reading' (duration: 138.148341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:24.483326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.928772ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-28T19:29:24.483523Z","caller":"traceutil/trace.go:171","msg":"trace[2070584614] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"1.075919342s","start":"2026-07-28T19:29:23.407592Z","end":"2026-07-28T19:29:24.483512Z","steps":["trace[2070584614] 'read index received' (duration: 19.361297ms)","trace[2070584614] 'applied index is now lower than readState.Index' (duration: 1.056557395s)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:29:24.48358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:23.4053Z","time spent":"1.078277557s","remote":"127.0.0.1:38128","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-28T19:29:24.483721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076124827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:29:24.483742Z","caller":"traceutil/trace.go:171","msg":"trace[1684298168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"1.076366924s","start":"2026-07-28T19:29:23.407369Z","end":"2026-07-28T19:29:24.483736Z","steps":["trace[1684298168] 'agreement among raft nodes before linearized reading' (duration: 1.076315633s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:24.483756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:23.407352Z","time spent":"1.076402025s","remote":"127.0.0.1:38106","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-07-28T19:29:24.483712Z","caller":"traceutil/trace.go:171","msg":"trace[1540398834] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.065724169s","start":"2026-07-28T19:29:23.417892Z","end":"2026-07-28T19:29:24.483616Z","steps":["trace[1540398834] 'process raft request' (duration: 1.065590795s)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:24.483903Z","caller":"traceutil/trace.go:171","msg":"trace[826934977] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.075453169s","start":"2026-07-28T19:29:23.408423Z","end":"2026-07-28T19:29:24.483876Z","steps":["trace[826934977] 'process raft request' (duration: 1.074966806s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:24.48395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:23.41788Z","time spent":"1.065963175s","remote":"127.0.0.1:38278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:29:24.483986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069965506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T19:29:24.484052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:23.408371Z","time spent":"1.075603513s","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:29:24.48408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.801081ms","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-07-28T19:29:24.484097Z","caller":"traceutil/trace.go:171","msg":"trace[1941810523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"676.856023ms","start":"2026-07-28T19:29:23.807237Z","end":"2026-07-28T19:29:24.484093Z","steps":["trace[1941810523] 'agreement among raft nodes before linearized reading' (duration: 676.789401ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:24.484051Z","caller":"traceutil/trace.go:171","msg":"trace[1692301427] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:591; }","duration":"1.070048519s","start":"2026-07-28T19:29:23.413982Z","end":"2026-07-28T19:29:24.48403Z","steps":["trace[1692301427] 'agreement among raft nodes before linearized reading' (duration: 1.069913115s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:24.484112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:23.807217Z","time spent":"676.892004ms","remote":"127.0.0.1:38352","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":"warn","ts":"2026-07-28T19:29:24.484192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:23.413971Z","time spent":"1.070150352s","remote":"127.0.0.1:38324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-28T19:29:24.484446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.925561ms","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-07-28T19:29:24.484557Z","caller":"traceutil/trace.go:171","msg":"trace[885622107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"701.104656ms","start":"2026-07-28T19:29:23.783427Z","end":"2026-07-28T19:29:24.484531Z","steps":["trace[885622107] 'agreement among raft nodes before linearized reading' (duration: 700.633083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:24.484854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:23.7834Z","time spent":"701.325182ms","remote":"127.0.0.1:38352","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":"info","ts":"2026-07-28T19:29:24.718277Z","caller":"traceutil/trace.go:171","msg":"trace[660354539] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"231.976711ms","start":"2026-07-28T19:29:24.486268Z","end":"2026-07-28T19:29:24.718245Z","steps":["trace[660354539] 'process raft request' (duration: 227.927729ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:24.718318Z","caller":"traceutil/trace.go:171","msg":"trace[2108656383] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:612; }","duration":"226.027508ms","start":"2026-07-28T19:29:24.49227Z","end":"2026-07-28T19:29:24.718298Z","steps":["trace[2108656383] 'read index received' (duration: 221.929915ms)","trace[2108656383] 'applied index is now lower than readState.Index' (duration: 4.096863ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:29:24.718456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.163572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:29:24.71848Z","caller":"traceutil/trace.go:171","msg":"trace[1003562901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"226.231624ms","start":"2026-07-28T19:29:24.492242Z","end":"2026-07-28T19:29:24.718474Z","steps":["trace[1003562901] 'agreement among raft nodes before linearized reading' (duration: 226.137152ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:24.718709Z","caller":"traceutil/trace.go:171","msg":"trace[1797414509] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"229.415421ms","start":"2026-07-28T19:29:24.489286Z","end":"2026-07-28T19:29:24.718702Z","steps":["trace[1797414509] 'process raft request' (duration: 228.885227ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:24.718792Z","caller":"traceutil/trace.go:171","msg":"trace[379770887] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"229.076132ms","start":"2026-07-28T19:29:24.489711Z","end":"2026-07-28T19:29:24.718787Z","steps":["trace[379770887] 'process raft request' (duration: 228.530107ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:26.412253Z","caller":"traceutil/trace.go:171","msg":"trace[1199035492] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"146.221259ms","start":"2026-07-28T19:29:26.266006Z","end":"2026-07-28T19:29:26.412227Z","steps":["trace[1199035492] 'read index received' (duration: 145.981483ms)","trace[1199035492] 'applied index is now lower than readState.Index' (duration: 238.706µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:29:26.412301Z","caller":"traceutil/trace.go:171","msg":"trace[1780646878] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"181.778736ms","start":"2026-07-28T19:29:26.230491Z","end":"2026-07-28T19:29:26.41227Z","steps":["trace[1780646878] 'process raft request' (duration: 181.586971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:26.412405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.391183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:29:26.412445Z","caller":"traceutil/trace.go:171","msg":"trace[1707445294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"146.446826ms","start":"2026-07-28T19:29:26.265985Z","end":"2026-07-28T19:29:26.412432Z","steps":["trace[1707445294] 'agreement among raft nodes before linearized reading' (duration: 146.347472ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:29.39735Z","caller":"traceutil/trace.go:171","msg":"trace[1556388779] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"161.632692ms","start":"2026-07-28T19:29:29.235696Z","end":"2026-07-28T19:29:29.397328Z","steps":["trace[1556388779] 'process raft request' (duration: 160.240606ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:29.397517Z","caller":"traceutil/trace.go:171","msg":"trace[411434395] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"131.76834ms","start":"2026-07-28T19:29:29.265513Z","end":"2026-07-28T19:29:29.397281Z","steps":["trace[411434395] 'read index received' (duration: 130.367422ms)","trace[411434395] 'applied index is now lower than readState.Index' (duration: 1.399937ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:29:29.397594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.060748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:29:29.397679Z","caller":"traceutil/trace.go:171","msg":"trace[1226416844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"132.203391ms","start":"2026-07-28T19:29:29.265454Z","end":"2026-07-28T19:29:29.397658Z","steps":["trace[1226416844] 'agreement among raft nodes before linearized reading' (duration: 131.990076ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:36.901194Z","caller":"traceutil/trace.go:171","msg":"trace[1926821638] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"114.732186ms","start":"2026-07-28T19:29:36.786433Z","end":"2026-07-28T19:29:36.901165Z","steps":["trace[1926821638] 'process raft request' (duration: 114.49666ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:36.901952Z","caller":"traceutil/trace.go:171","msg":"trace[888290666] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"114.246465ms","start":"2026-07-28T19:29:36.787666Z","end":"2026-07-28T19:29:36.901913Z","steps":["trace[888290666] 'process raft request' (duration: 113.858605ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:39.616351Z","caller":"traceutil/trace.go:171","msg":"trace[1655070281] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"107.11553ms","start":"2026-07-28T19:29:39.509212Z","end":"2026-07-28T19:29:39.616328Z","steps":["trace[1655070281] 'process raft request' (duration: 106.965656ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:48.787639Z","caller":"traceutil/trace.go:171","msg":"trace[159661536] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"107.714875ms","start":"2026-07-28T19:29:48.679905Z","end":"2026-07-28T19:29:48.78762Z","steps":["trace[159661536] 'process raft request' (duration: 107.579892ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:54.56645Z","caller":"traceutil/trace.go:171","msg":"trace[639339569] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"148.960211ms","start":"2026-07-28T19:29:54.417461Z","end":"2026-07-28T19:29:54.566421Z","steps":["trace[639339569] 'read index received' (duration: 101.828458ms)","trace[639339569] 'applied index is now lower than readState.Index' (duration: 47.130303ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:29:54.566604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.119214ms","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-07-28T19:29:54.566637Z","caller":"traceutil/trace.go:171","msg":"trace[1775557734] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:919; }","duration":"149.219937ms","start":"2026-07-28T19:29:54.417407Z","end":"2026-07-28T19:29:54.566627Z","steps":["trace[1775557734] 'agreement among raft nodes before linearized reading' (duration: 149.113494ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:54.566926Z","caller":"traceutil/trace.go:171","msg":"trace[1934085539] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"201.402307ms","start":"2026-07-28T19:29:54.365511Z","end":"2026-07-28T19:29:54.566913Z","steps":["trace[1934085539] 'process raft request' (duration: 153.899415ms)","trace[1934085539] 'compare' (duration: 46.49541ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:30:11.427798Z","caller":"traceutil/trace.go:171","msg":"trace[1659050041] linearizableReadLoop","detail":"{readStateIndex:1190; appliedIndex:1189; }","duration":"191.070845ms","start":"2026-07-28T19:30:11.236699Z","end":"2026-07-28T19:30:11.42777Z","steps":["trace[1659050041] 'read index received' (duration: 190.88896ms)","trace[1659050041] 'applied index is now lower than readState.Index' (duration: 180.595µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:30:11.427884Z","caller":"traceutil/trace.go:171","msg":"trace[1045637361] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"319.521692ms","start":"2026-07-28T19:30:11.108324Z","end":"2026-07-28T19:30:11.427845Z","steps":["trace[1045637361] 'process raft request' (duration: 319.247856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:11.428024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:11.108303Z","time spent":"319.644266ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:30:11.428031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.322689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-28T19:30:11.42807Z","caller":"traceutil/trace.go:171","msg":"trace[1137480628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1158; }","duration":"191.436031ms","start":"2026-07-28T19:30:11.236624Z","end":"2026-07-28T19:30:11.42806Z","steps":["trace[1137480628] 'agreement among raft nodes before linearized reading' (duration: 191.269618ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:30:11.693864Z","caller":"traceutil/trace.go:171","msg":"trace[647775274] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"260.511705ms","start":"2026-07-28T19:30:11.433295Z","end":"2026-07-28T19:30:11.693807Z","steps":["trace[647775274] 'process raft request' (duration: 224.257631ms)","trace[647775274] 'compare' (duration: 36.029669ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:30:11.694053Z","caller":"traceutil/trace.go:171","msg":"trace[1089835691] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1190; }","duration":"169.457882ms","start":"2026-07-28T19:30:11.524561Z","end":"2026-07-28T19:30:11.694019Z","steps":["trace[1089835691] 'read index received' (duration: 133.013515ms)","trace[1089835691] 'applied index is now lower than readState.Index' (duration: 36.442857ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:30:11.69426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.667186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T19:30:11.694787Z","caller":"traceutil/trace.go:171","msg":"trace[2078779288] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1160; }","duration":"170.280457ms","start":"2026-07-28T19:30:11.524491Z","end":"2026-07-28T19:30:11.694771Z","steps":["trace[2078779288] 'agreement among raft nodes before linearized reading' (duration: 169.672686ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:30:11.694957Z","caller":"traceutil/trace.go:171","msg":"trace[936426713] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"102.65294ms","start":"2026-07-28T19:30:11.591387Z","end":"2026-07-28T19:30:11.69404Z","steps":["trace[936426713] 'process raft request' (duration: 102.502437ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:30:27.903189Z","caller":"traceutil/trace.go:171","msg":"trace[1784587546] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"140.028812ms","start":"2026-07-28T19:30:27.763136Z","end":"2026-07-28T19:30:27.903165Z","steps":["trace[1784587546] 'read index received' (duration: 139.867329ms)","trace[1784587546] 'applied index is now lower than readState.Index' (duration: 159.923µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:30:27.903431Z","caller":"traceutil/trace.go:171","msg":"trace[1670522393] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"149.434521ms","start":"2026-07-28T19:30:27.753793Z","end":"2026-07-28T19:30:27.903227Z","steps":["trace[1670522393] 'process raft request' (duration: 149.202547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:27.90353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.366037ms","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-07-28T19:30:27.903765Z","caller":"traceutil/trace.go:171","msg":"trace[2025085134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1299; }","duration":"140.655692ms","start":"2026-07-28T19:30:27.763083Z","end":"2026-07-28T19:30:27.903739Z","steps":["trace[2025085134] 'agreement among raft nodes before linearized reading' (duration: 140.242155ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:30:34.847228Z","caller":"traceutil/trace.go:171","msg":"trace[1388232678] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"261.244937ms","start":"2026-07-28T19:30:34.58595Z","end":"2026-07-28T19:30:34.847195Z","steps":["trace[1388232678] 'process raft request' (duration: 261.029683ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:30:59.070238Z","caller":"traceutil/trace.go:171","msg":"trace[1815584275] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"356.346016ms","start":"2026-07-28T19:30:58.713834Z","end":"2026-07-28T19:30:59.07018Z","steps":["trace[1815584275] 'process raft request' (duration: 356.020231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:59.070502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:58.713811Z","time spent":"356.555ms","remote":"127.0.0.1:38278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:30:59.604923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824477465280933,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T19:31:00.105812Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824477465280933,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T19:31:00.152933Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.076245409s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-28T19:31:00.408516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.219987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T19:31:00.408689Z","caller":"traceutil/trace.go:171","msg":"trace[468503986] linearizableReadLoop","detail":"{readStateIndex:1894; appliedIndex:1892; }","duration":"1.304242085s","start":"2026-07-28T19:30:59.10443Z","end":"2026-07-28T19:31:00.408672Z","steps":["trace[468503986] 'read index received' (duration: 1.048787306s)","trace[468503986] 'applied index is now lower than readState.Index' (duration: 255.453799ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:31:00.408928Z","caller":"traceutil/trace.go:171","msg":"trace[315581370] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"1.332357448s","start":"2026-07-28T19:30:59.076527Z","end":"2026-07-28T19:31:00.408884Z","steps":["trace[315581370] 'process raft request' (duration: 1.076674705s)","trace[315581370] 'compare' (duration: 254.974503ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:31:00.408987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274022204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-28T19:31:00.409028Z","caller":"traceutil/trace.go:171","msg":"trace[1553267999] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1825; }","duration":"1.274071194s","start":"2026-07-28T19:30:59.134929Z","end":"2026-07-28T19:31:00.409Z","steps":["trace[1553267999] 'agreement among raft nodes before linearized reading' (duration: 1.273956262s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:00.409133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.425533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-07-28T19:31:00.409136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:59.076499Z","time spent":"1.33253634s","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:31:00.409158Z","caller":"traceutil/trace.go:171","msg":"trace[1268479142] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1825; }","duration":"511.487523ms","start":"2026-07-28T19:30:59.897663Z","end":"2026-07-28T19:31:00.40915Z","steps":["trace[1268479142] 'agreement among raft nodes before linearized reading' (duration: 511.408812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:00.409177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:59.89764Z","time spent":"511.531114ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-28T19:31:00.409221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136598047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-07-28T19:31:00.409338Z","caller":"traceutil/trace.go:171","msg":"trace[1140710900] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1825; }","duration":"1.136731249s","start":"2026-07-28T19:30:59.272578Z","end":"2026-07-28T19:31:00.409309Z","steps":["trace[1140710900] 'agreement among raft nodes before linearized reading' (duration: 1.136522176s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:00.409387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:59.272546Z","time spent":"1.136829461s","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-28T19:31:00.409776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.649757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:31:00.409788Z","caller":"traceutil/trace.go:171","msg":"trace[1691109886] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"364.142409ms","start":"2026-07-28T19:31:00.045635Z","end":"2026-07-28T19:31:00.409778Z","steps":["trace[1691109886] 'process raft request' (duration: 362.999552ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:00.409812Z","caller":"traceutil/trace.go:171","msg":"trace[831281029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1825; }","duration":"143.737289ms","start":"2026-07-28T19:31:00.266062Z","end":"2026-07-28T19:31:00.4098Z","steps":["trace[831281029] 'agreement among raft nodes before linearized reading' (duration: 143.681178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:00.409838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.776025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-28T19:31:00.409839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:00.045603Z","time spent":"364.209859ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:31:00.409859Z","caller":"traceutil/trace.go:171","msg":"trace[1000867570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1825; }","duration":"281.846186ms","start":"2026-07-28T19:31:00.128007Z","end":"2026-07-28T19:31:00.409853Z","steps":["trace[1000867570] 'agreement among raft nodes before linearized reading' (duration: 281.787055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:00.410085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:59.134912Z","time spent":"1.27516541s","remote":"127.0.0.1:38328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-28T19:31:00.410091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238309733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-28T19:31:00.410092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305661507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0a3c13d1-a751-4c09-96e4-0a8d3bbc58ec\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-07-28T19:31:00.410119Z","caller":"traceutil/trace.go:171","msg":"trace[1322672112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1825; }","duration":"1.238358813s","start":"2026-07-28T19:30:59.171752Z","end":"2026-07-28T19:31:00.410111Z","steps":["trace[1322672112] 'agreement among raft nodes before linearized reading' (duration: 1.238207051s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:00.41014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:59.171741Z","time spent":"1.238394485s","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-28T19:31:00.410137Z","caller":"traceutil/trace.go:171","msg":"trace[1125665558] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0a3c13d1-a751-4c09-96e4-0a8d3bbc58ec; range_end:; response_count:1; response_revision:1825; }","duration":"1.305767207s","start":"2026-07-28T19:30:59.104357Z","end":"2026-07-28T19:31:00.410124Z","steps":["trace[1125665558] 'agreement among raft nodes before linearized reading' (duration: 1.304478199s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:00.410172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:59.104324Z","time spent":"1.305839399s","remote":"127.0.0.1:38278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0a3c13d1-a751-4c09-96e4-0a8d3bbc58ec\" "} {"level":"warn","ts":"2026-07-28T19:31:00.410684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.748337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-28T19:31:00.411318Z","caller":"traceutil/trace.go:171","msg":"trace[1583399549] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1825; }","duration":"326.41196ms","start":"2026-07-28T19:31:00.084554Z","end":"2026-07-28T19:31:00.410966Z","steps":["trace[1583399549] 'agreement among raft nodes before linearized reading' (duration: 324.484122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:00.411582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.262134ms","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-07-28T19:31:00.411623Z","caller":"traceutil/trace.go:171","msg":"trace[1139548317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1825; }","duration":"714.366186ms","start":"2026-07-28T19:30:59.697247Z","end":"2026-07-28T19:31:00.411613Z","steps":["trace[1139548317] 'agreement among raft nodes before linearized reading' (duration: 711.80919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:00.411641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:59.697222Z","time spent":"714.414917ms","remote":"127.0.0.1:38352","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-07-28T19:31:00.411704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:00.084535Z","time spent":"327.14422ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-28T19:31:00.53934Z","caller":"traceutil/trace.go:171","msg":"trace[27536162] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"123.912645ms","start":"2026-07-28T19:31:00.415405Z","end":"2026-07-28T19:31:00.539318Z","steps":["trace[27536162] 'process raft request' (duration: 94.470435ms)","trace[27536162] 'compare' (duration: 29.325788ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:31:00.58775Z","caller":"traceutil/trace.go:171","msg":"trace[124765397] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"171.544253ms","start":"2026-07-28T19:31:00.416179Z","end":"2026-07-28T19:31:00.587723Z","steps":["trace[124765397] 'process raft request' (duration: 171.052506ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:00.58783Z","caller":"traceutil/trace.go:171","msg":"trace[1833094941] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"171.502873ms","start":"2026-07-28T19:31:00.416299Z","end":"2026-07-28T19:31:00.587802Z","steps":["trace[1833094941] 'process raft request' (duration: 170.968966ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:00.588008Z","caller":"traceutil/trace.go:171","msg":"trace[1608725843] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"171.475203ms","start":"2026-07-28T19:31:00.416518Z","end":"2026-07-28T19:31:00.587993Z","steps":["trace[1608725843] 'process raft request' (duration: 170.783433ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:00.588029Z","caller":"traceutil/trace.go:171","msg":"trace[1481969604] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"172.219813ms","start":"2026-07-28T19:31:00.415799Z","end":"2026-07-28T19:31:00.588019Z","steps":["trace[1481969604] 'process raft request' (duration: 171.26376ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:00.588187Z","caller":"traceutil/trace.go:171","msg":"trace[1985449823] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"172.374256ms","start":"2026-07-28T19:31:00.415803Z","end":"2026-07-28T19:31:00.588177Z","steps":["trace[1985449823] 'process raft request' (duration: 171.385222ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:14.70738Z","caller":"traceutil/trace.go:171","msg":"trace[1832533691] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"242.933831ms","start":"2026-07-28T19:31:14.464407Z","end":"2026-07-28T19:31:14.707341Z","steps":["trace[1832533691] 'process raft request' (duration: 223.383526ms)","trace[1832533691] 'compare' (duration: 19.086598ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:31:14.707454Z","caller":"traceutil/trace.go:171","msg":"trace[1437019644] linearizableReadLoop","detail":"{readStateIndex:2022; appliedIndex:2021; }","duration":"224.91529ms","start":"2026-07-28T19:31:14.482509Z","end":"2026-07-28T19:31:14.707424Z","steps":["trace[1437019644] 'read index received' (duration: 205.253033ms)","trace[1437019644] 'applied index is now lower than readState.Index' (duration: 19.660287ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:31:14.707625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.082442ms","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-07-28T19:31:14.707689Z","caller":"traceutil/trace.go:171","msg":"trace[1079164695] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1950; }","duration":"225.190293ms","start":"2026-07-28T19:31:14.482484Z","end":"2026-07-28T19:31:14.707675Z","steps":["trace[1079164695] 'agreement among raft nodes before linearized reading' (duration: 225.016631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:14.708244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.165922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-28T19:31:14.708287Z","caller":"traceutil/trace.go:171","msg":"trace[1619146412] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1951; }","duration":"188.230053ms","start":"2026-07-28T19:31:14.520047Z","end":"2026-07-28T19:31:14.708277Z","steps":["trace[1619146412] 'agreement among raft nodes before linearized reading' (duration: 188.052561ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:35.638988Z","caller":"traceutil/trace.go:171","msg":"trace[1966202314] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"227.184867ms","start":"2026-07-28T19:31:35.411769Z","end":"2026-07-28T19:31:35.638954Z","steps":["trace[1966202314] 'process raft request' (duration: 226.995275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:36.377202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.60315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:31:36.377349Z","caller":"traceutil/trace.go:171","msg":"trace[18145841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2079; }","duration":"111.817363ms","start":"2026-07-28T19:31:36.265503Z","end":"2026-07-28T19:31:36.37732Z","steps":["trace[18145841] 'range keys from in-memory index tree' (duration: 111.459308ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:36.997095Z","caller":"traceutil/trace.go:171","msg":"trace[198161853] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"203.414309ms","start":"2026-07-28T19:31:36.793653Z","end":"2026-07-28T19:31:36.997067Z","steps":["trace[198161853] 'process raft request' (duration: 203.238867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:37.532403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.878733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T19:31:37.532732Z","caller":"traceutil/trace.go:171","msg":"trace[2062667712] linearizableReadLoop","detail":"{readStateIndex:2157; appliedIndex:2156; }","duration":"719.253666ms","start":"2026-07-28T19:31:36.813448Z","end":"2026-07-28T19:31:37.532702Z","steps":["trace[2062667712] 'read index received' (duration: 183.782061ms)","trace[2062667712] 'applied index is now lower than readState.Index' (duration: 535.468645ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:31:37.532936Z","caller":"traceutil/trace.go:171","msg":"trace[1468482912] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"738.51798ms","start":"2026-07-28T19:31:36.794403Z","end":"2026-07-28T19:31:37.532921Z","steps":["trace[1468482912] 'process raft request' (duration: 462.910297ms)","trace[1468482912] 'compare' (duration: 274.412318ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:31:37.533163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:36.794366Z","time spent":"738.720643ms","remote":"127.0.0.1:38352","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-07-28T19:31:37.533252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.406223ms","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-07-28T19:31:37.533276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.53575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-07-28T19:31:37.533303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.099526ms","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-07-28T19:31:37.533303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.598511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-28T19:31:37.533401Z","caller":"traceutil/trace.go:171","msg":"trace[834891577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2081; }","duration":"689.238128ms","start":"2026-07-28T19:31:36.844143Z","end":"2026-07-28T19:31:37.533381Z","steps":["trace[834891577] 'agreement among raft nodes before linearized reading' (duration: 688.967064ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:37.533401Z","caller":"traceutil/trace.go:171","msg":"trace[960992977] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2081; }","duration":"601.701812ms","start":"2026-07-28T19:31:36.931671Z","end":"2026-07-28T19:31:37.533373Z","steps":["trace[960992977] 'agreement among raft nodes before linearized reading' (duration: 601.374488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:37.533412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.31462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-07-28T19:31:37.533442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:36.844111Z","time spent":"689.323768ms","remote":"127.0.0.1:38352","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":"info","ts":"2026-07-28T19:31:37.533441Z","caller":"traceutil/trace.go:171","msg":"trace[1726349751] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2081; }","duration":"624.769643ms","start":"2026-07-28T19:31:36.908638Z","end":"2026-07-28T19:31:37.533407Z","steps":["trace[1726349751] 'agreement among raft nodes before linearized reading' (duration: 624.428039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:37.533457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:36.931661Z","time spent":"601.785813ms","remote":"127.0.0.1:38328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-28T19:31:37.533472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.469794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T19:31:37.533471Z","caller":"traceutil/trace.go:171","msg":"trace[1631520045] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2081; }","duration":"625.390832ms","start":"2026-07-28T19:31:36.908061Z","end":"2026-07-28T19:31:37.533452Z","steps":["trace[1631520045] 'agreement among raft nodes before linearized reading' (duration: 625.27024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:37.533496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:36.90863Z","time spent":"624.855734ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-28T19:31:37.533506Z","caller":"traceutil/trace.go:171","msg":"trace[1348512507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2081; }","duration":"694.536264ms","start":"2026-07-28T19:31:36.83896Z","end":"2026-07-28T19:31:37.533497Z","steps":["trace[1348512507] 'agreement among raft nodes before linearized reading' (duration: 694.441063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:37.533521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:36.908047Z","time spent":"625.467112ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-28T19:31:37.533558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:36.838947Z","time spent":"694.603445ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-28T19:31:37.533367Z","caller":"traceutil/trace.go:171","msg":"trace[605501851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2081; }","duration":"694.535524ms","start":"2026-07-28T19:31:36.838803Z","end":"2026-07-28T19:31:37.533338Z","steps":["trace[605501851] 'agreement among raft nodes before linearized reading' (duration: 694.244981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:37.533658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:36.838738Z","time spent":"694.909679ms","remote":"127.0.0.1:38352","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":"warn","ts":"2026-07-28T19:31:37.533282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.945514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-28T19:31:37.533663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.026083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T19:31:37.533751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.31357ms","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-07-28T19:31:37.533769Z","caller":"traceutil/trace.go:171","msg":"trace[508816426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2081; }","duration":"263.164845ms","start":"2026-07-28T19:31:37.270587Z","end":"2026-07-28T19:31:37.533752Z","steps":["trace[508816426] 'agreement among raft nodes before linearized reading' (duration: 263.048944ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:37.533791Z","caller":"traceutil/trace.go:171","msg":"trace[273215522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2081; }","duration":"720.384751ms","start":"2026-07-28T19:31:36.813396Z","end":"2026-07-28T19:31:37.533781Z","steps":["trace[273215522] 'agreement among raft nodes before linearized reading' (duration: 720.282699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:37.533826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:36.813382Z","time spent":"720.438181ms","remote":"127.0.0.1:38352","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":"info","ts":"2026-07-28T19:31:37.533839Z","caller":"traceutil/trace.go:171","msg":"trace[1209913398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2081; }","duration":"670.44459ms","start":"2026-07-28T19:31:36.863297Z","end":"2026-07-28T19:31:37.533742Z","steps":["trace[1209913398] 'agreement among raft nodes before linearized reading' (duration: 669.756882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:37.533932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:36.863285Z","time spent":"670.632482ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-28T19:31:37.534101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.053706ms","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-07-28T19:31:37.534145Z","caller":"traceutil/trace.go:171","msg":"trace[1929659040] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2081; }","duration":"464.103867ms","start":"2026-07-28T19:31:37.070031Z","end":"2026-07-28T19:31:37.534135Z","steps":["trace[1929659040] 'agreement among raft nodes before linearized reading' (duration: 463.625781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:37.534166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:37.069932Z","time spent":"464.227868ms","remote":"127.0.0.1:38278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":22,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-28T19:31:37.662576Z","caller":"traceutil/trace.go:171","msg":"trace[1092900002] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"123.362654ms","start":"2026-07-28T19:31:37.539173Z","end":"2026-07-28T19:31:37.662536Z","steps":["trace[1092900002] 'process raft request' (duration: 97.157983ms)","trace[1092900002] 'compare' (duration: 26.026899ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:31:38.245731Z","caller":"traceutil/trace.go:171","msg":"trace[1590303451] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"706.33741ms","start":"2026-07-28T19:31:37.539359Z","end":"2026-07-28T19:31:38.245696Z","steps":["trace[1590303451] 'process raft request' (duration: 630.298195ms)","trace[1590303451] 'compare' (duration: 75.713691ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:31:38.246472Z","caller":"traceutil/trace.go:171","msg":"trace[2044462006] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"706.890317ms","start":"2026-07-28T19:31:37.539549Z","end":"2026-07-28T19:31:38.246439Z","steps":["trace[2044462006] 'process raft request' (duration: 706.059197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:38.246605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:37.539537Z","time spent":"707.014098ms","remote":"127.0.0.1:38352","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-07-28T19:31:38.246608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:37.539329Z","time spent":"707.100649ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:31:38.247259Z","caller":"traceutil/trace.go:171","msg":"trace[1395688647] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"707.535496ms","start":"2026-07-28T19:31:37.539606Z","end":"2026-07-28T19:31:38.247142Z","steps":["trace[1395688647] 'process raft request' (duration: 706.271309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:38.247517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:37.539593Z","time spent":"707.803099ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:31:38.248264Z","caller":"traceutil/trace.go:171","msg":"trace[321796467] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"707.375753ms","start":"2026-07-28T19:31:37.540868Z","end":"2026-07-28T19:31:38.248244Z","steps":["trace[321796467] 'process raft request' (duration: 706.886817ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:38.248342Z","caller":"traceutil/trace.go:171","msg":"trace[569919378] linearizableReadLoop","detail":"{readStateIndex:2164; appliedIndex:2158; }","duration":"377.346186ms","start":"2026-07-28T19:31:37.870984Z","end":"2026-07-28T19:31:38.248331Z","steps":["trace[569919378] 'read index received' (duration: 298.684528ms)","trace[569919378] 'applied index is now lower than readState.Index' (duration: 78.660958ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:31:38.248476Z","caller":"traceutil/trace.go:171","msg":"trace[307757574] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"707.487885ms","start":"2026-07-28T19:31:37.540979Z","end":"2026-07-28T19:31:38.248467Z","steps":["trace[307757574] 'process raft request' (duration: 707.11529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:38.248529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:37.540958Z","time spent":"707.542765ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:31:38.247831Z","caller":"traceutil/trace.go:171","msg":"trace[1365609010] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"707.442885ms","start":"2026-07-28T19:31:37.540359Z","end":"2026-07-28T19:31:38.247802Z","steps":["trace[1365609010] 'process raft request' (duration: 707.213472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:38.248587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.978339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T19:31:38.248663Z","caller":"traceutil/trace.go:171","msg":"trace[1126133178] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2088; }","duration":"222.119051ms","start":"2026-07-28T19:31:38.026526Z","end":"2026-07-28T19:31:38.248645Z","steps":["trace[1126133178] 'agreement among raft nodes before linearized reading' (duration: 221.976759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:38.24874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:37.54035Z","time spent":"708.291075ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:31:38.248658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:37.540856Z","time spent":"707.487605ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:31:38.248934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.935753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c68af525db3911\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-28T19:31:38.249066Z","caller":"traceutil/trace.go:171","msg":"trace[634401222] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c68af525db3911; range_end:; response_count:1; response_revision:2088; }","duration":"378.152606ms","start":"2026-07-28T19:31:37.870871Z","end":"2026-07-28T19:31:38.249024Z","steps":["trace[634401222] 'agreement among raft nodes before linearized reading' (duration: 377.793861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:38.249198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:37.870837Z","time spent":"378.272828ms","remote":"127.0.0.1:38164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":870,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c68af525db3911\" "} {"level":"info","ts":"2026-07-28T19:32:38.887701Z","caller":"traceutil/trace.go:171","msg":"trace[1585156356] linearizableReadLoop","detail":"{readStateIndex:2598; appliedIndex:2596; }","duration":"287.303828ms","start":"2026-07-28T19:32:38.600364Z","end":"2026-07-28T19:32:38.887667Z","steps":["trace[1585156356] 'read index received' (duration: 36.005677ms)","trace[1585156356] 'applied index is now lower than readState.Index' (duration: 251.296771ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:32:38.887813Z","caller":"traceutil/trace.go:171","msg":"trace[242323373] transaction","detail":"{read_only:false; response_revision:2508; number_of_response:1; }","duration":"299.045911ms","start":"2026-07-28T19:32:38.588739Z","end":"2026-07-28T19:32:38.887785Z","steps":["trace[242323373] 'process raft request' (duration: 298.792588ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:32:38.887935Z","caller":"traceutil/trace.go:171","msg":"trace[1477444716] transaction","detail":"{read_only:false; response_revision:2507; number_of_response:1; }","duration":"302.300359ms","start":"2026-07-28T19:32:38.585603Z","end":"2026-07-28T19:32:38.887903Z","steps":["trace[1477444716] 'process raft request' (duration: 299.397266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:32:38.888166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:32:38.585575Z","time spent":"302.43508ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:32:38.888192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.819255ms","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-07-28T19:32:38.888237Z","caller":"traceutil/trace.go:171","msg":"trace[1923392214] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2508; }","duration":"287.893716ms","start":"2026-07-28T19:32:38.600331Z","end":"2026-07-28T19:32:38.888224Z","steps":["trace[1923392214] 'agreement among raft nodes before linearized reading' (duration: 287.692274ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:32:38.898709Z","caller":"traceutil/trace.go:171","msg":"trace[1505546939] transaction","detail":"{read_only:false; response_revision:2509; number_of_response:1; }","duration":"189.184058ms","start":"2026-07-28T19:32:38.709473Z","end":"2026-07-28T19:32:38.898657Z","steps":["trace[1505546939] 'process raft request' (duration: 188.953566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:32:38.900437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.683559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-28T19:32:38.900502Z","caller":"traceutil/trace.go:171","msg":"trace[1103746338] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2509; }","duration":"253.80059ms","start":"2026-07-28T19:32:38.646684Z","end":"2026-07-28T19:32:38.900485Z","steps":["trace[1103746338] 'agreement among raft nodes before linearized reading' (duration: 253.494857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:32:38.900502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.006239ms","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-07-28T19:32:38.900585Z","caller":"traceutil/trace.go:171","msg":"trace[2013389367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2509; }","duration":"252.126201ms","start":"2026-07-28T19:32:38.648435Z","end":"2026-07-28T19:32:38.900561Z","steps":["trace[2013389367] 'agreement among raft nodes before linearized reading' (duration: 251.749787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:32:39.818977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.596677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-28T19:32:39.819077Z","caller":"traceutil/trace.go:171","msg":"trace[561976534] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2513; }","duration":"432.758879ms","start":"2026-07-28T19:32:39.386298Z","end":"2026-07-28T19:32:39.819057Z","steps":["trace[561976534] 'count revisions from in-memory index tree' (duration: 432.499416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:32:39.819127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:32:39.386279Z","time spent":"432.836659ms","remote":"127.0.0.1:38634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":270,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-28T19:33:11.630815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.293613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T19:33:11.630972Z","caller":"traceutil/trace.go:171","msg":"trace[1149072800] transaction","detail":"{read_only:false; response_revision:2686; number_of_response:1; }","duration":"550.348159ms","start":"2026-07-28T19:33:11.080582Z","end":"2026-07-28T19:33:11.63093Z","steps":["trace[1149072800] 'process raft request' (duration: 225.824638ms)","trace[1149072800] 'compare' (duration: 322.751394ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:33:11.631061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:33:11.080559Z","time spent":"550.463874ms","remote":"127.0.0.1:38352","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-07-28T19:33:11.68157Z","caller":"traceutil/trace.go:171","msg":"trace[1826903597] transaction","detail":"{read_only:false; response_revision:2687; number_of_response:1; }","duration":"600.106666ms","start":"2026-07-28T19:33:11.081431Z","end":"2026-07-28T19:33:11.681537Z","steps":["trace[1826903597] 'process raft request' (duration: 599.39608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:33:11.681762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:33:11.081416Z","time spent":"600.244842ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:33:11.681796Z","caller":"traceutil/trace.go:171","msg":"trace[1752326352] linearizableReadLoop","detail":"{readStateIndex:2785; appliedIndex:2783; }","duration":"598.722925ms","start":"2026-07-28T19:33:11.083058Z","end":"2026-07-28T19:33:11.681781Z","steps":["trace[1752326352] 'read index received' (duration: 223.328363ms)","trace[1752326352] 'applied index is now lower than readState.Index' (duration: 375.393682ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:33:11.681933Z","caller":"traceutil/trace.go:171","msg":"trace[2139642383] transaction","detail":"{read_only:false; response_revision:2688; number_of_response:1; }","duration":"598.358181ms","start":"2026-07-28T19:33:11.083558Z","end":"2026-07-28T19:33:11.681916Z","steps":["trace[2139642383] 'process raft request' (duration: 597.916094ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:33:11.681923Z","caller":"traceutil/trace.go:171","msg":"trace[935066421] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"597.491167ms","start":"2026-07-28T19:33:11.084376Z","end":"2026-07-28T19:33:11.681867Z","steps":["trace[935066421] 'process raft request' (duration: 597.344821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:33:11.681995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.932122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9280"} {"level":"warn","ts":"2026-07-28T19:33:11.682001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:33:11.083532Z","time spent":"598.435014ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:33:11.682033Z","caller":"traceutil/trace.go:171","msg":"trace[662592974] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2689; }","duration":"598.986615ms","start":"2026-07-28T19:33:11.083037Z","end":"2026-07-28T19:33:11.682024Z","steps":["trace[662592974] 'agreement among raft nodes before linearized reading' (duration: 598.819988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:33:11.682059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:33:11.083028Z","time spent":"599.025836ms","remote":"127.0.0.1:38274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":9304,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-28T19:33:11.682105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:33:11.084359Z","time spent":"597.644203ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:33:11.689263Z","caller":"traceutil/trace.go:171","msg":"trace[515781838] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"328.128989ms","start":"2026-07-28T19:33:11.361099Z","end":"2026-07-28T19:33:11.689228Z","steps":["trace[515781838] 'process raft request' (duration: 327.972863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:33:11.689513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:33:11.361064Z","time spent":"328.353017ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:33:11.68996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.139421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:33:11.69004Z","caller":"traceutil/trace.go:171","msg":"trace[645205245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2690; }","duration":"424.273026ms","start":"2026-07-28T19:33:11.265753Z","end":"2026-07-28T19:33:11.690026Z","steps":["trace[645205245] 'agreement among raft nodes before linearized reading' (duration: 424.11101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:33:11.690074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:33:11.265728Z","time spent":"424.339469ms","remote":"127.0.0.1:38110","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-07-28T19:33:12.130708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.900121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:33:12.130826Z","caller":"traceutil/trace.go:171","msg":"trace[1659372909] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2690; }","duration":"267.078138ms","start":"2026-07-28T19:33:11.863721Z","end":"2026-07-28T19:33:12.1308Z","steps":["trace[1659372909] 'range keys from in-memory index tree' (duration: 266.813548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:33:12.130823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.025716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T19:33:12.130908Z","caller":"traceutil/trace.go:171","msg":"trace[495387121] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2690; }","duration":"189.176982ms","start":"2026-07-28T19:33:11.941711Z","end":"2026-07-28T19:33:12.130888Z","steps":["trace[495387121] 'count revisions from in-memory index tree' (duration: 188.949254ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:33:12.131202Z","caller":"traceutil/trace.go:171","msg":"trace[8146904] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"134.874203ms","start":"2026-07-28T19:33:11.996287Z","end":"2026-07-28T19:33:12.131161Z","steps":["trace[8146904] 'process raft request' (duration: 104.701365ms)","trace[8146904] 'compare' (duration: 29.996801ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:33:17.684518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.7685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T19:33:17.684638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.527977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c68b08d2c15e2c\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-28T19:33:17.684724Z","caller":"traceutil/trace.go:171","msg":"trace[149324043] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c68b08d2c15e2c; range_end:; response_count:1; response_revision:2715; }","duration":"370.647641ms","start":"2026-07-28T19:33:17.314054Z","end":"2026-07-28T19:33:17.684701Z","steps":["trace[149324043] 'range keys from in-memory index tree' (duration: 370.364441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:33:17.684817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.153026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:33:17.684897Z","caller":"traceutil/trace.go:171","msg":"trace[2081950058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2715; }","duration":"419.25584ms","start":"2026-07-28T19:33:17.26563Z","end":"2026-07-28T19:33:17.684886Z","steps":["trace[2081950058] 'range keys from in-memory index tree' (duration: 419.100575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:33:17.684957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:33:17.265619Z","time spent":"419.326424ms","remote":"127.0.0.1:38106","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-07-28T19:33:17.684681Z","caller":"traceutil/trace.go:171","msg":"trace[159899043] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2715; }","duration":"438.981757ms","start":"2026-07-28T19:33:17.245679Z","end":"2026-07-28T19:33:17.684661Z","steps":["trace[159899043] 'range keys from in-memory index tree' (duration: 438.698697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:33:17.685277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:33:17.245657Z","time spent":"439.60747ms","remote":"127.0.0.1:38554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-28T19:33:17.684777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:33:17.314043Z","time spent":"370.724544ms","remote":"127.0.0.1:38164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":805,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c68b08d2c15e2c\" "} {"level":"warn","ts":"2026-07-28T19:33:17.684638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.033408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-28T19:33:17.685997Z","caller":"traceutil/trace.go:171","msg":"trace[2006841526] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2715; }","duration":"201.456719ms","start":"2026-07-28T19:33:17.484518Z","end":"2026-07-28T19:33:17.685974Z","steps":["trace[2006841526] 'range keys from in-memory index tree' (duration: 199.842511ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:37:12.794457Z","caller":"traceutil/trace.go:171","msg":"trace[1905403185] transaction","detail":"{read_only:false; response_revision:4484; number_of_response:1; }","duration":"129.195414ms","start":"2026-07-28T19:37:12.665228Z","end":"2026-07-28T19:37:12.794424Z","steps":["trace[1905403185] 'process raft request' (duration: 121.435578ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:37:16.075072Z","caller":"traceutil/trace.go:171","msg":"trace[1215532450] transaction","detail":"{read_only:false; response_revision:4561; number_of_response:1; }","duration":"233.758405ms","start":"2026-07-28T19:37:15.841274Z","end":"2026-07-28T19:37:16.075033Z","steps":["trace[1215532450] 'process raft request' (duration: 231.873663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:17.584963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824477465287880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T19:37:18.004203Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.095315126s","expected-duration":"1s"} {"level":"info","ts":"2026-07-28T19:37:18.004823Z","caller":"traceutil/trace.go:171","msg":"trace[2013834752] transaction","detail":"{read_only:false; response_revision:4594; number_of_response:1; }","duration":"1.111200382s","start":"2026-07-28T19:37:16.893584Z","end":"2026-07-28T19:37:18.004784Z","steps":["trace[2013834752] 'process raft request' (duration: 1.110949898s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.005104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:16.893547Z","time spent":"1.111391405s","remote":"127.0.0.1:38566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15286,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:37:18.055166Z","caller":"traceutil/trace.go:171","msg":"trace[2131132805] linearizableReadLoop","detail":"{readStateIndex:4769; appliedIndex:4766; }","duration":"971.119073ms","start":"2026-07-28T19:37:17.084025Z","end":"2026-07-28T19:37:18.055144Z","steps":["trace[2131132805] 'read index received' (duration: 920.450698ms)","trace[2131132805] 'applied index is now lower than readState.Index' (duration: 50.667235ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:37:18.055537Z","caller":"traceutil/trace.go:171","msg":"trace[1882855779] transaction","detail":"{read_only:false; response_revision:4595; number_of_response:1; }","duration":"954.659429ms","start":"2026-07-28T19:37:17.100863Z","end":"2026-07-28T19:37:18.055523Z","steps":["trace[1882855779] 'process raft request' (duration: 952.546823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.055644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:17.100834Z","time spent":"954.744599ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:37:18.055842Z","caller":"traceutil/trace.go:171","msg":"trace[1877821473] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"806.725418ms","start":"2026-07-28T19:37:17.249105Z","end":"2026-07-28T19:37:18.05583Z","steps":["trace[1877821473] 'process raft request' (duration: 805.975625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.055905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:17.249076Z","time spent":"806.799659ms","remote":"127.0.0.1:38164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T19:37:18.056171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.14349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-28T19:37:18.056213Z","caller":"traceutil/trace.go:171","msg":"trace[2024120484] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4596; }","duration":"972.215953ms","start":"2026-07-28T19:37:17.083987Z","end":"2026-07-28T19:37:18.056203Z","steps":["trace[2024120484] 'agreement among raft nodes before linearized reading' (duration: 972.065969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.056249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:17.083968Z","time spent":"972.274804ms","remote":"127.0.0.1:38278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-28T19:37:18.057176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.793085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d6e29077-a075-4c72-859d-0effce9ef417\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-07-28T19:37:18.057257Z","caller":"traceutil/trace.go:171","msg":"trace[1000559193] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d6e29077-a075-4c72-859d-0effce9ef417; range_end:; response_count:1; response_revision:4596; }","duration":"463.932136ms","start":"2026-07-28T19:37:17.593302Z","end":"2026-07-28T19:37:18.057234Z","steps":["trace[1000559193] 'agreement among raft nodes before linearized reading' (duration: 463.618871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.0573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:17.593289Z","time spent":"463.999838ms","remote":"127.0.0.1:38278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3797,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d6e29077-a075-4c72-859d-0effce9ef417\" "} {"level":"warn","ts":"2026-07-28T19:37:18.057292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.989233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-28T19:37:18.057371Z","caller":"traceutil/trace.go:171","msg":"trace[276641161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4596; }","duration":"536.118685ms","start":"2026-07-28T19:37:17.521232Z","end":"2026-07-28T19:37:18.057351Z","steps":["trace[276641161] 'agreement among raft nodes before linearized reading' (duration: 535.856701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.057366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.29427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-07-28T19:37:18.057404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:17.521214Z","time spent":"536.183766ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T19:37:18.057526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.721224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c426b8ee-c5e0-493a-bd5e-585236480d43\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-07-28T19:37:18.057591Z","caller":"traceutil/trace.go:171","msg":"trace[884977047] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c426b8ee-c5e0-493a-bd5e-585236480d43; range_end:; response_count:1; response_revision:4596; }","duration":"748.836146ms","start":"2026-07-28T19:37:17.30873Z","end":"2026-07-28T19:37:18.057567Z","steps":["trace[884977047] 'agreement among raft nodes before linearized reading' (duration: 748.699444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.057594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.130334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d6e29077-a075-4c72-859d-0effce9ef417\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-07-28T19:37:18.057624Z","caller":"traceutil/trace.go:171","msg":"trace[495231589] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d6e29077-a075-4c72-859d-0effce9ef417; range_end:; response_count:1; response_revision:4596; }","duration":"466.194425ms","start":"2026-07-28T19:37:17.591421Z","end":"2026-07-28T19:37:18.057616Z","steps":["trace[495231589] 'agreement among raft nodes before linearized reading' (duration: 466.115434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.057672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:17.591403Z","time spent":"466.264726ms","remote":"127.0.0.1:38278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3797,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d6e29077-a075-4c72-859d-0effce9ef417\" "} {"level":"warn","ts":"2026-07-28T19:37:18.057622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:17.308719Z","time spent":"748.896107ms","remote":"127.0.0.1:38278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3783,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c426b8ee-c5e0-493a-bd5e-585236480d43\" "} {"level":"warn","ts":"2026-07-28T19:37:18.057912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.869296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T19:37:18.057931Z","caller":"traceutil/trace.go:171","msg":"trace[1088289906] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:4596; }","duration":"774.932108ms","start":"2026-07-28T19:37:17.282993Z","end":"2026-07-28T19:37:18.057925Z","steps":["trace[1088289906] 'agreement among raft nodes before linearized reading' (duration: 774.838857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.057949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:17.282972Z","time spent":"774.971709ms","remote":"127.0.0.1:38194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":47,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-28T19:37:18.059241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.466119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-28T19:37:18.059363Z","caller":"traceutil/trace.go:171","msg":"trace[705006316] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4596; }","duration":"923.635261ms","start":"2026-07-28T19:37:17.135704Z","end":"2026-07-28T19:37:18.059339Z","steps":["trace[705006316] 'agreement among raft nodes before linearized reading' (duration: 923.300385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.059412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:17.135691Z","time spent":"923.710993ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-28T19:37:18.059647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.117766ms","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-07-28T19:37:18.05968Z","caller":"traceutil/trace.go:171","msg":"trace[1471370326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4596; }","duration":"918.176227ms","start":"2026-07-28T19:37:17.141496Z","end":"2026-07-28T19:37:18.059672Z","steps":["trace[1471370326] 'agreement among raft nodes before linearized reading' (duration: 918.063915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.059705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:17.141485Z","time spent":"918.216077ms","remote":"127.0.0.1:38352","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-07-28T19:37:18.059711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.873095ms","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-07-28T19:37:18.05975Z","caller":"traceutil/trace.go:171","msg":"trace[1198166699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4596; }","duration":"923.957687ms","start":"2026-07-28T19:37:17.135781Z","end":"2026-07-28T19:37:18.059739Z","steps":["trace[1198166699] 'agreement among raft nodes before linearized reading' (duration: 923.845715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.059778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:17.135752Z","time spent":"924.020478ms","remote":"127.0.0.1:38352","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":"warn","ts":"2026-07-28T19:37:18.059883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.409761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-28T19:37:18.059908Z","caller":"traceutil/trace.go:171","msg":"trace[400759530] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4596; }","duration":"803.471432ms","start":"2026-07-28T19:37:17.256429Z","end":"2026-07-28T19:37:18.059901Z","steps":["trace[400759530] 'agreement among raft nodes before linearized reading' (duration: 803.38717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.059947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:17.256409Z","time spent":"803.532613ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-28T19:37:18.058649Z","caller":"traceutil/trace.go:171","msg":"trace[1704872366] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4596; }","duration":"142.416612ms","start":"2026-07-28T19:37:17.914994Z","end":"2026-07-28T19:37:18.05741Z","steps":["trace[1704872366] 'agreement among raft nodes before linearized reading' (duration: 142.25304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:18.429235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.358113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:37:18.42933Z","caller":"traceutil/trace.go:171","msg":"trace[1423409102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4607; }","duration":"163.500345ms","start":"2026-07-28T19:37:18.265809Z","end":"2026-07-28T19:37:18.429309Z","steps":["trace[1423409102] 'range keys from in-memory index tree' (duration: 163.272452ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:37:20.331372Z","caller":"traceutil/trace.go:171","msg":"trace[537134878] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"163.292283ms","start":"2026-07-28T19:37:20.168056Z","end":"2026-07-28T19:37:20.331349Z","steps":["trace[537134878] 'process raft request' (duration: 128.92599ms)","trace[537134878] 'compare' (duration: 34.265871ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:37:20.335765Z","caller":"traceutil/trace.go:171","msg":"trace[481220332] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"166.69024ms","start":"2026-07-28T19:37:20.169058Z","end":"2026-07-28T19:37:20.335748Z","steps":["trace[481220332] 'process raft request' (duration: 166.545177ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:37:20.335939Z","caller":"traceutil/trace.go:171","msg":"trace[1406485242] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"166.298154ms","start":"2026-07-28T19:37:20.169636Z","end":"2026-07-28T19:37:20.335934Z","steps":["trace[1406485242] 'process raft request' (duration: 166.07636ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:37:20.336072Z","caller":"traceutil/trace.go:171","msg":"trace[1985982106] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"165.619302ms","start":"2026-07-28T19:37:20.170447Z","end":"2026-07-28T19:37:20.336066Z","steps":["trace[1985982106] 'process raft request' (duration: 165.457559ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:37:20.33617Z","caller":"traceutil/trace.go:171","msg":"trace[2094322260] linearizableReadLoop","detail":"{readStateIndex:4837; appliedIndex:4831; }","duration":"164.736177ms","start":"2026-07-28T19:37:20.171427Z","end":"2026-07-28T19:37:20.336163Z","steps":["trace[2094322260] 'read index received' (duration: 125.566912ms)","trace[2094322260] 'applied index is now lower than readState.Index' (duration: 39.168475ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:37:20.336213Z","caller":"traceutil/trace.go:171","msg":"trace[649612494] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"164.887569ms","start":"2026-07-28T19:37:20.171322Z","end":"2026-07-28T19:37:20.336209Z","steps":["trace[649612494] 'process raft request' (duration: 164.721206ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:37:20.33632Z","caller":"traceutil/trace.go:171","msg":"trace[105392928] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"164.96641ms","start":"2026-07-28T19:37:20.171349Z","end":"2026-07-28T19:37:20.336316Z","steps":["trace[105392928] 'process raft request' (duration: 164.790427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:20.336468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.058612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T19:37:20.33649Z","caller":"traceutil/trace.go:171","msg":"trace[1482986882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4661; }","duration":"165.119094ms","start":"2026-07-28T19:37:20.171365Z","end":"2026-07-28T19:37:20.336485Z","steps":["trace[1482986882] 'agreement among raft nodes before linearized reading' (duration: 164.997921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:25.656175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.377221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:37:25.656299Z","caller":"traceutil/trace.go:171","msg":"trace[419165721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4782; }","duration":"390.573526ms","start":"2026-07-28T19:37:25.265694Z","end":"2026-07-28T19:37:25.656268Z","steps":["trace[419165721] 'range keys from in-memory index tree' (duration: 390.27318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:25.656355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:25.265667Z","time spent":"390.677818ms","remote":"127.0.0.1:38110","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-07-28T19:37:25.656423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.43943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-922846d3-5773-49d0-91b8-ac14d4cbc8c5\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-07-28T19:37:25.656483Z","caller":"traceutil/trace.go:171","msg":"trace[409159236] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-922846d3-5773-49d0-91b8-ac14d4cbc8c5; range_end:; response_count:1; response_revision:4782; }","duration":"743.658823ms","start":"2026-07-28T19:37:24.912809Z","end":"2026-07-28T19:37:25.656467Z","steps":["trace[409159236] 'range keys from in-memory index tree' (duration: 743.204766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:25.656514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:24.912784Z","time spent":"743.723494ms","remote":"127.0.0.1:38278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-922846d3-5773-49d0-91b8-ac14d4cbc8c5\" "} {"level":"info","ts":"2026-07-28T19:37:26.169602Z","caller":"traceutil/trace.go:171","msg":"trace[1503259648] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"127.881193ms","start":"2026-07-28T19:37:26.041692Z","end":"2026-07-28T19:37:26.169573Z","steps":["trace[1503259648] 'process raft request' (duration: 127.68999ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:37:26.285503Z","caller":"traceutil/trace.go:171","msg":"trace[1296867502] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"103.574517ms","start":"2026-07-28T19:37:26.181874Z","end":"2026-07-28T19:37:26.285448Z","steps":["trace[1296867502] 'process raft request' (duration: 72.368645ms)","trace[1296867502] 'compare' (duration: 30.745445ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:37:29.402436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.973907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T19:37:29.402531Z","caller":"traceutil/trace.go:171","msg":"trace[1046974018] linearizableReadLoop","detail":"{readStateIndex:5005; appliedIndex:5004; }","duration":"136.52651ms","start":"2026-07-28T19:37:29.26599Z","end":"2026-07-28T19:37:29.402516Z","steps":["trace[1046974018] 'read index received' (duration: 12.330369ms)","trace[1046974018] 'applied index is now lower than readState.Index' (duration: 124.194571ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:37:29.402625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.634401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:37:29.402616Z","caller":"traceutil/trace.go:171","msg":"trace[1005496468] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"162.627413ms","start":"2026-07-28T19:37:29.239946Z","end":"2026-07-28T19:37:29.402574Z","steps":["trace[1005496468] 'process raft request' (duration: 38.429672ms)","trace[1005496468] 'compare' (duration: 123.862865ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:37:29.402697Z","caller":"traceutil/trace.go:171","msg":"trace[1620575095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4826; }","duration":"136.705293ms","start":"2026-07-28T19:37:29.265984Z","end":"2026-07-28T19:37:29.40269Z","steps":["trace[1620575095] 'agreement among raft nodes before linearized reading' (duration: 136.5677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:31.286626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.678496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:713"} {"level":"info","ts":"2026-07-28T19:37:31.286711Z","caller":"traceutil/trace.go:171","msg":"trace[146085481] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4859; }","duration":"204.833969ms","start":"2026-07-28T19:37:31.081853Z","end":"2026-07-28T19:37:31.286687Z","steps":["trace[146085481] 'range keys from in-memory index tree' (duration: 204.337672ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:37:40.12789Z","caller":"traceutil/trace.go:171","msg":"trace[1668794419] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"259.649105ms","start":"2026-07-28T19:37:39.868204Z","end":"2026-07-28T19:37:40.127853Z","steps":["trace[1668794419] 'process raft request' (duration: 240.156769ms)","trace[1668794419] 'compare' (duration: 19.186931ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:37:40.722942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.569653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T19:37:40.723058Z","caller":"traceutil/trace.go:171","msg":"trace[1935415695] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"586.442469ms","start":"2026-07-28T19:37:40.136592Z","end":"2026-07-28T19:37:40.723034Z","steps":["trace[1935415695] 'process raft request' (duration: 317.690954ms)","trace[1935415695] 'compare' (duration: 268.366798ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:37:40.723125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:40.136557Z","time spent":"586.53551ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:37:40.737833Z","caller":"traceutil/trace.go:171","msg":"trace[438631737] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"601.028872ms","start":"2026-07-28T19:37:40.136784Z","end":"2026-07-28T19:37:40.737813Z","steps":["trace[438631737] 'process raft request' (duration: 600.732007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:40.737921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:40.136739Z","time spent":"601.147194ms","remote":"127.0.0.1:38360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:37:40.738022Z","caller":"traceutil/trace.go:171","msg":"trace[353402640] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"600.90298ms","start":"2026-07-28T19:37:40.13709Z","end":"2026-07-28T19:37:40.737993Z","steps":["trace[353402640] 'process raft request' (duration: 600.590504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:40.738192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:40.13708Z","time spent":"601.023572ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:37:40.738243Z","caller":"traceutil/trace.go:171","msg":"trace[1339452153] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"600.30561ms","start":"2026-07-28T19:37:40.137911Z","end":"2026-07-28T19:37:40.738217Z","steps":["trace[1339452153] 'process raft request' (duration: 599.813612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:40.738338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:40.137896Z","time spent":"600.409032ms","remote":"127.0.0.1:38360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1505,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:37:40.738506Z","caller":"traceutil/trace.go:171","msg":"trace[2101602829] linearizableReadLoop","detail":"{readStateIndex:5159; appliedIndex:5155; }","duration":"583.987208ms","start":"2026-07-28T19:37:40.154504Z","end":"2026-07-28T19:37:40.738491Z","steps":["trace[2101602829] 'read index received' (duration: 299.743544ms)","trace[2101602829] 'applied index is now lower than readState.Index' (duration: 284.243164ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:37:40.738627Z","caller":"traceutil/trace.go:171","msg":"trace[192791554] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"403.213213ms","start":"2026-07-28T19:37:40.33538Z","end":"2026-07-28T19:37:40.738593Z","steps":["trace[192791554] 'process raft request' (duration: 403.058871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:40.738926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.426795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T19:37:40.738982Z","caller":"traceutil/trace.go:171","msg":"trace[546350670] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:4977; }","duration":"584.545147ms","start":"2026-07-28T19:37:40.154425Z","end":"2026-07-28T19:37:40.73897Z","steps":["trace[546350670] 'agreement among raft nodes before linearized reading' (duration: 584.437676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:40.739286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:40.154395Z","time spent":"584.840542ms","remote":"127.0.0.1:38578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-07-28T19:37:40.738957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:40.335337Z","time spent":"403.46145ms","remote":"127.0.0.1:38352","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-07-28T19:37:40.778875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.746523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T19:37:40.778931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.447473ms","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-07-28T19:37:40.778972Z","caller":"traceutil/trace.go:171","msg":"trace[1433295115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4978; }","duration":"203.514194ms","start":"2026-07-28T19:37:40.575447Z","end":"2026-07-28T19:37:40.778961Z","steps":["trace[1433295115] 'agreement among raft nodes before linearized reading' (duration: 203.321161ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:37:40.778966Z","caller":"traceutil/trace.go:171","msg":"trace[272597360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4978; }","duration":"513.889106ms","start":"2026-07-28T19:37:40.265053Z","end":"2026-07-28T19:37:40.778942Z","steps":["trace[272597360] 'agreement among raft nodes before linearized reading' (duration: 513.661932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:40.77902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:37:40.265037Z","time spent":"513.970487ms","remote":"127.0.0.1:38110","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-07-28T19:37:40.779154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.50017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-28T19:37:40.779153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.121455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-28T19:37:40.779183Z","caller":"traceutil/trace.go:171","msg":"trace[197089801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4978; }","duration":"192.563401ms","start":"2026-07-28T19:37:40.586612Z","end":"2026-07-28T19:37:40.779175Z","steps":["trace[197089801] 'agreement among raft nodes before linearized reading' (duration: 192.476829ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:37:40.779206Z","caller":"traceutil/trace.go:171","msg":"trace[1458182125] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4978; }","duration":"225.221337ms","start":"2026-07-28T19:37:40.553965Z","end":"2026-07-28T19:37:40.779186Z","steps":["trace[1458182125] 'agreement among raft nodes before linearized reading' (duration: 225.093585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:40.779356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.483678ms","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-07-28T19:37:40.779402Z","caller":"traceutil/trace.go:171","msg":"trace[1060288030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4978; }","duration":"226.56918ms","start":"2026-07-28T19:37:40.552822Z","end":"2026-07-28T19:37:40.779391Z","steps":["trace[1060288030] 'agreement among raft nodes before linearized reading' (duration: 226.417688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:40.779423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.334593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-28T19:37:40.779444Z","caller":"traceutil/trace.go:171","msg":"trace[633164215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4978; }","duration":"233.386703ms","start":"2026-07-28T19:37:40.546051Z","end":"2026-07-28T19:37:40.779438Z","steps":["trace[633164215] 'agreement among raft nodes before linearized reading' (duration: 233.336332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:40.779374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.583563ms","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-07-28T19:37:40.779483Z","caller":"traceutil/trace.go:171","msg":"trace[1655028943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4978; }","duration":"283.744365ms","start":"2026-07-28T19:37:40.495727Z","end":"2026-07-28T19:37:40.779472Z","steps":["trace[1655028943] 'agreement among raft nodes before linearized reading' (duration: 283.602653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:40.779511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.430917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-07-28T19:37:40.779656Z","caller":"traceutil/trace.go:171","msg":"trace[959944789] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4978; }","duration":"226.627201ms","start":"2026-07-28T19:37:40.552996Z","end":"2026-07-28T19:37:40.779624Z","steps":["trace[959944789] 'agreement among raft nodes before linearized reading' (duration: 225.749477ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:38:15.302649Z","caller":"traceutil/trace.go:171","msg":"trace[2117912724] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"150.738176ms","start":"2026-07-28T19:38:15.151885Z","end":"2026-07-28T19:38:15.302624Z","steps":["trace[2117912724] 'process raft request' (duration: 150.405911ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:38:19.267592Z","caller":"traceutil/trace.go:171","msg":"trace[239837037] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"107.19137ms","start":"2026-07-28T19:38:19.160375Z","end":"2026-07-28T19:38:19.267567Z","steps":["trace[239837037] 'process raft request' (duration: 107.004987ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:38:19.268016Z","caller":"traceutil/trace.go:171","msg":"trace[1064265350] transaction","detail":"{read_only:false; response_revision:5306; number_of_response:1; }","duration":"107.494114ms","start":"2026-07-28T19:38:19.160505Z","end":"2026-07-28T19:38:19.267999Z","steps":["trace[1064265350] 'process raft request' (duration: 107.012557ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:38:19.26816Z","caller":"traceutil/trace.go:171","msg":"trace[1653121733] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"106.866485ms","start":"2026-07-28T19:38:19.161279Z","end":"2026-07-28T19:38:19.268146Z","steps":["trace[1653121733] 'process raft request' (duration: 106.56151ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:38:25.473749Z","caller":"traceutil/trace.go:171","msg":"trace[320362907] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"152.421397ms","start":"2026-07-28T19:38:25.321289Z","end":"2026-07-28T19:38:25.47371Z","steps":["trace[320362907] 'process raft request' (duration: 152.199382ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:38:25.490789Z","caller":"traceutil/trace.go:171","msg":"trace[251204646] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"169.401136ms","start":"2026-07-28T19:38:25.321363Z","end":"2026-07-28T19:38:25.490764Z","steps":["trace[251204646] 'process raft request' (duration: 169.224343ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:38:25.491151Z","caller":"traceutil/trace.go:171","msg":"trace[2008475499] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"169.484168ms","start":"2026-07-28T19:38:25.321638Z","end":"2026-07-28T19:38:25.491122Z","steps":["trace[2008475499] 'process raft request' (duration: 169.088631ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:38:40.388354Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2841} {"level":"info","ts":"2026-07-28T19:38:40.492019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2841,"took":"101.287924ms","hash":2210944142,"current-db-size-bytes":54304768,"current-db-size":"54 MB","current-db-size-in-use-bytes":29331456,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-28T19:38:40.492096Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2210944142,"revision":2841,"compact-revision":-1} {"level":"info","ts":"2026-07-28T19:41:16.189123Z","caller":"traceutil/trace.go:171","msg":"trace[2083605355] linearizableReadLoop","detail":"{readStateIndex:6776; appliedIndex:6775; }","duration":"174.509461ms","start":"2026-07-28T19:41:16.014567Z","end":"2026-07-28T19:41:16.189076Z","steps":["trace[2083605355] 'read index received' (duration: 174.317959ms)","trace[2083605355] 'applied index is now lower than readState.Index' (duration: 187.032µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:41:16.189187Z","caller":"traceutil/trace.go:171","msg":"trace[970442686] transaction","detail":"{read_only:false; response_revision:6542; number_of_response:1; }","duration":"215.486199ms","start":"2026-07-28T19:41:15.973659Z","end":"2026-07-28T19:41:16.189146Z","steps":["trace[970442686] 'process raft request' (duration: 215.103444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:41:16.189524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.909107ms","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-07-28T19:41:16.189642Z","caller":"traceutil/trace.go:171","msg":"trace[1519626148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6542; }","duration":"175.10525ms","start":"2026-07-28T19:41:16.014507Z","end":"2026-07-28T19:41:16.189612Z","steps":["trace[1519626148] 'agreement among raft nodes before linearized reading' (duration: 174.759905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:41:16.202091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.997888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-28T19:41:16.202166Z","caller":"traceutil/trace.go:171","msg":"trace[124854235] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6544; }","duration":"170.098479ms","start":"2026-07-28T19:41:16.032048Z","end":"2026-07-28T19:41:16.202146Z","steps":["trace[124854235] 'agreement among raft nodes before linearized reading' (duration: 169.150784ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:41:38.634296Z","caller":"traceutil/trace.go:171","msg":"trace[774240761] transaction","detail":"{read_only:false; response_revision:6723; number_of_response:1; }","duration":"111.377503ms","start":"2026-07-28T19:41:38.522885Z","end":"2026-07-28T19:41:38.634263Z","steps":["trace[774240761] 'process raft request' (duration: 111.343762ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:41:38.634327Z","caller":"traceutil/trace.go:171","msg":"trace[1191304076] transaction","detail":"{read_only:false; response_revision:6721; number_of_response:1; }","duration":"114.959291ms","start":"2026-07-28T19:41:38.519307Z","end":"2026-07-28T19:41:38.634267Z","steps":["trace[1191304076] 'process raft request' (duration: 114.757035ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:41:38.634521Z","caller":"traceutil/trace.go:171","msg":"trace[505734] transaction","detail":"{read_only:false; response_revision:6722; number_of_response:1; }","duration":"112.462105ms","start":"2026-07-28T19:41:38.522034Z","end":"2026-07-28T19:41:38.634496Z","steps":["trace[505734] 'process raft request' (duration: 112.155295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:41:39.087025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.090043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T19:41:39.087121Z","caller":"traceutil/trace.go:171","msg":"trace[1810813192] linearizableReadLoop","detail":"{readStateIndex:6968; appliedIndex:6967; }","duration":"133.354511ms","start":"2026-07-28T19:41:38.95375Z","end":"2026-07-28T19:41:39.087104Z","steps":["trace[1810813192] 'read index received' (duration: 39.281µs)","trace[1810813192] 'applied index is now lower than readState.Index' (duration: 133.31411ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:41:39.087329Z","caller":"traceutil/trace.go:171","msg":"trace[160002105] transaction","detail":"{read_only:false; response_revision:6725; number_of_response:1; }","duration":"376.423248ms","start":"2026-07-28T19:41:38.710889Z","end":"2026-07-28T19:41:39.087312Z","steps":["trace[160002105] 'process raft request' (duration: 79.956503ms)","trace[160002105] 'compare' (duration: 295.764994ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:41:39.087498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:41:38.710849Z","time spent":"376.614304ms","remote":"127.0.0.1:38164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":655,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:41:39.087341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.588827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107255"} {"level":"info","ts":"2026-07-28T19:41:39.087709Z","caller":"traceutil/trace.go:171","msg":"trace[1474517193] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6725; }","duration":"133.937818ms","start":"2026-07-28T19:41:38.953742Z","end":"2026-07-28T19:41:39.087679Z","steps":["trace[1474517193] 'agreement among raft nodes before linearized reading' (duration: 133.412392ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:41:57.939955Z","caller":"traceutil/trace.go:171","msg":"trace[1021009033] transaction","detail":"{read_only:false; response_revision:6822; number_of_response:1; }","duration":"118.478924ms","start":"2026-07-28T19:41:57.821455Z","end":"2026-07-28T19:41:57.939934Z","steps":["trace[1021009033] 'process raft request' (duration: 118.364051ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:43:40.398134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5471} {"level":"info","ts":"2026-07-28T19:43:40.482497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5471,"took":"82.706361ms","hash":3087050282,"current-db-size-bytes":54304768,"current-db-size":"54 MB","current-db-size-in-use-bytes":18350080,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-28T19:43:40.482576Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3087050282,"revision":5471,"compact-revision":2841}