{"level":"warn","ts":"2026-10-14T19:33:50.57248Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T19:33:50.572551Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.128:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.128:2380","--initial-cluster=instance=https://199.204.45.128:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.128:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.128: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-10-14T19:33:50.572608Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T19:33:50.572631Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.128:2380"]} {"level":"info","ts":"2026-10-14T19:33:50.572648Z","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-10-14T19:33:50.573198Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.128:2379"]} {"level":"info","ts":"2026-10-14T19:33:50.573265Z","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.204.45.128:2380"],"listen-peer-urls":["https://199.204.45.128:2380"],"advertise-client-urls":["https://199.204.45.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.128: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-10-14T19:33:50.577202Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.744267ms"} {"level":"info","ts":"2026-10-14T19:33:50.582918Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"87640f981dfdb068","cluster-id":"dd9c881e7167bb48"} {"level":"info","ts":"2026-10-14T19:33:50.582971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"87640f981dfdb068 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T19:33:50.582986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"87640f981dfdb068 became follower at term 0"} {"level":"info","ts":"2026-10-14T19:33:50.582994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 87640f981dfdb068 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T19:33:50.583003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"87640f981dfdb068 became follower at term 1"} {"level":"info","ts":"2026-10-14T19:33:50.583032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"87640f981dfdb068 switched to configuration voters=(9755939838803947624)"} {"level":"warn","ts":"2026-10-14T19:33:50.589277Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T19:33:50.590712Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T19:33:50.591983Z","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-10-14T19:33:50.593919Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"87640f981dfdb068","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T19:33:50.594311Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"87640f981dfdb068","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T19:33:50.594379Z","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-10-14T19:33:50.594699Z","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-10-14T19:33:50.594751Z","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-10-14T19:33:50.596711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"87640f981dfdb068 switched to configuration voters=(9755939838803947624)"} {"level":"info","ts":"2026-10-14T19:33:50.596826Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dd9c881e7167bb48","local-member-id":"87640f981dfdb068","added-peer-id":"87640f981dfdb068","added-peer-peer-urls":["https://199.204.45.128:2380"]} {"level":"info","ts":"2026-10-14T19:33:50.597744Z","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-10-14T19:33:50.597847Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.128:2380"} {"level":"info","ts":"2026-10-14T19:33:50.597914Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.128:2380"} {"level":"info","ts":"2026-10-14T19:33:50.598118Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"87640f981dfdb068","initial-advertise-peer-urls":["https://199.204.45.128:2380"],"listen-peer-urls":["https://199.204.45.128:2380"],"advertise-client-urls":["https://199.204.45.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T19:33:50.598194Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T19:33:50.78369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"87640f981dfdb068 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T19:33:50.783746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"87640f981dfdb068 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T19:33:50.783765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"87640f981dfdb068 received MsgPreVoteResp from 87640f981dfdb068 at term 1"} {"level":"info","ts":"2026-10-14T19:33:50.783782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"87640f981dfdb068 became candidate at term 2"} {"level":"info","ts":"2026-10-14T19:33:50.783792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"87640f981dfdb068 received MsgVoteResp from 87640f981dfdb068 at term 2"} {"level":"info","ts":"2026-10-14T19:33:50.783812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"87640f981dfdb068 became leader at term 2"} {"level":"info","ts":"2026-10-14T19:33:50.783824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 87640f981dfdb068 elected leader 87640f981dfdb068 at term 2"} {"level":"info","ts":"2026-10-14T19:33:50.78506Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T19:33:50.785344Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"87640f981dfdb068","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.128:2379]}","request-path":"/0/members/87640f981dfdb068/attributes","cluster-id":"dd9c881e7167bb48","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T19:33:50.785451Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T19:33:50.785438Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T19:33:50.785845Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T19:33:50.785933Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T19:33:50.787098Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T19:33:50.787612Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dd9c881e7167bb48","local-member-id":"87640f981dfdb068","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T19:33:50.787665Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T19:33:50.787682Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T19:33:50.787938Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.128:2379"} {"level":"info","ts":"2026-10-14T19:34:34.71015Z","caller":"traceutil/trace.go:171","msg":"trace[853786216] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"123.908942ms","start":"2026-10-14T19:34:34.586226Z","end":"2026-10-14T19:34:34.710135Z","steps":["trace[853786216] 'process raft request' (duration: 123.77801ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:34.719917Z","caller":"traceutil/trace.go:171","msg":"trace[979371041] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"133.642001ms","start":"2026-10-14T19:34:34.586245Z","end":"2026-10-14T19:34:34.719887Z","steps":["trace[979371041] 'process raft request' (duration: 133.449138ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:34.720583Z","caller":"traceutil/trace.go:171","msg":"trace[1333592161] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"134.20197ms","start":"2026-10-14T19:34:34.586341Z","end":"2026-10-14T19:34:34.720543Z","steps":["trace[1333592161] 'process raft request' (duration: 133.439347ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:34.720703Z","caller":"traceutil/trace.go:171","msg":"trace[245986588] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"122.502619ms","start":"2026-10-14T19:34:34.598192Z","end":"2026-10-14T19:34:34.720695Z","steps":["trace[245986588] 'process raft request' (duration: 121.626974ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:34.720769Z","caller":"traceutil/trace.go:171","msg":"trace[1561077712] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"119.910974ms","start":"2026-10-14T19:34:34.600852Z","end":"2026-10-14T19:34:34.720763Z","steps":["trace[1561077712] 'process raft request' (duration: 119.004708ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:34.720837Z","caller":"traceutil/trace.go:171","msg":"trace[1091685380] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"119.820632ms","start":"2026-10-14T19:34:34.601009Z","end":"2026-10-14T19:34:34.72083Z","steps":["trace[1091685380] 'process raft request' (duration: 118.881766ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:34.720906Z","caller":"traceutil/trace.go:171","msg":"trace[1648757836] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"115.765712ms","start":"2026-10-14T19:34:34.605132Z","end":"2026-10-14T19:34:34.720898Z","steps":["trace[1648757836] 'process raft request' (duration: 114.833706ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:34.720969Z","caller":"traceutil/trace.go:171","msg":"trace[52888103] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"115.163451ms","start":"2026-10-14T19:34:34.605797Z","end":"2026-10-14T19:34:34.720961Z","steps":["trace[52888103] 'process raft request' (duration: 114.212375ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:35.368981Z","caller":"traceutil/trace.go:171","msg":"trace[2057566403] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"108.7303ms","start":"2026-10-14T19:34:35.260226Z","end":"2026-10-14T19:34:35.368956Z","steps":["trace[2057566403] 'process raft request' (duration: 108.549677ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:35.817191Z","caller":"traceutil/trace.go:171","msg":"trace[929668989] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"139.750394ms","start":"2026-10-14T19:34:35.677414Z","end":"2026-10-14T19:34:35.817165Z","steps":["trace[929668989] 'process raft request' (duration: 79.882304ms)","trace[929668989] 'compare' (duration: 59.753688ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:34:35.877944Z","caller":"traceutil/trace.go:171","msg":"trace[1688630421] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"119.75797ms","start":"2026-10-14T19:34:35.758168Z","end":"2026-10-14T19:34:35.877926Z","steps":["trace[1688630421] 'process raft request' (duration: 119.656568ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:35.99066Z","caller":"traceutil/trace.go:171","msg":"trace[1916742240] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"115.529577ms","start":"2026-10-14T19:34:35.875106Z","end":"2026-10-14T19:34:35.990635Z","steps":["trace[1916742240] 'process raft request' (duration: 79.618609ms)","trace[1916742240] 'compare' (duration: 35.549552ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:34:40.784883Z","caller":"traceutil/trace.go:171","msg":"trace[1745996468] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"186.781091ms","start":"2026-10-14T19:34:40.598074Z","end":"2026-10-14T19:34:40.784855Z","steps":["trace[1745996468] 'process raft request' (duration: 186.626948ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:41.084044Z","caller":"traceutil/trace.go:171","msg":"trace[1623816750] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:639; }","duration":"326.085239ms","start":"2026-10-14T19:34:40.757935Z","end":"2026-10-14T19:34:41.08402Z","steps":["trace[1623816750] 'read index received' (duration: 26.818229ms)","trace[1623816750] 'applied index is now lower than readState.Index' (duration: 299.26616ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:34:41.084224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.270962ms","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-10-14T19:34:41.084277Z","caller":"traceutil/trace.go:171","msg":"trace[442866932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"326.399894ms","start":"2026-10-14T19:34:40.757866Z","end":"2026-10-14T19:34:41.084266Z","steps":["trace[442866932] 'agreement among raft nodes before linearized reading' (duration: 326.240111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:34:41.084309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:34:40.757848Z","time spent":"326.453685ms","remote":"127.0.0.1:43142","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-10-14T19:34:41.084555Z","caller":"traceutil/trace.go:171","msg":"trace[764084228] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"357.731129ms","start":"2026-10-14T19:34:40.726794Z","end":"2026-10-14T19:34:41.084525Z","steps":["trace[764084228] 'process raft request' (duration: 356.683282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:34:41.084991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:34:40.72676Z","time spent":"357.819161ms","remote":"127.0.0.1:42948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T19:34:41.755603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.634757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T19:34:41.755756Z","caller":"traceutil/trace.go:171","msg":"trace[375366145] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"599.759623ms","start":"2026-10-14T19:34:41.155979Z","end":"2026-10-14T19:34:41.755739Z","steps":["trace[375366145] 'process raft request' (duration: 599.711413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:34:41.755808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:34:41.155951Z","time spent":"599.834264ms","remote":"127.0.0.1:43360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-14T19:34:41.755817Z","caller":"traceutil/trace.go:171","msg":"trace[57406807] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"651.13589ms","start":"2026-10-14T19:34:41.104653Z","end":"2026-10-14T19:34:41.755789Z","steps":["trace[57406807] 'read index received' (duration: 128.108797ms)","trace[57406807] 'applied index is now lower than readState.Index' (duration: 523.024463ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:34:41.755941Z","caller":"traceutil/trace.go:171","msg":"trace[1775214257] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"664.578804ms","start":"2026-10-14T19:34:41.091349Z","end":"2026-10-14T19:34:41.755928Z","steps":["trace[1775214257] 'process raft request' (duration: 141.417519ms)","trace[1775214257] 'compare' (duration: 522.351992ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:34:41.756049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:34:41.091321Z","time spent":"664.651325ms","remote":"127.0.0.1:43142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T19:34:41.756121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.645681ms","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-10-14T19:34:41.756154Z","caller":"traceutil/trace.go:171","msg":"trace[2072476422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"500.744082ms","start":"2026-10-14T19:34:41.255399Z","end":"2026-10-14T19:34:41.756143Z","steps":["trace[2072476422] 'agreement among raft nodes before linearized reading' (duration: 500.60748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:34:41.75618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:34:41.255388Z","time spent":"500.785763ms","remote":"127.0.0.1:43142","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":"warn","ts":"2026-10-14T19:34:41.756175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.460286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:34:41.756256Z","caller":"traceutil/trace.go:171","msg":"trace[365761964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"651.611748ms","start":"2026-10-14T19:34:41.104622Z","end":"2026-10-14T19:34:41.756234Z","steps":["trace[365761964] 'agreement among raft nodes before linearized reading' (duration: 651.441685ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:34:41.756298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:34:41.104605Z","time spent":"651.68403ms","remote":"127.0.0.1:42860","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-10-14T19:34:41.756228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.598923ms","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-10-14T19:34:41.756401Z","caller":"traceutil/trace.go:171","msg":"trace[1381884038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"487.786366ms","start":"2026-10-14T19:34:41.268593Z","end":"2026-10-14T19:34:41.75638Z","steps":["trace[1381884038] 'agreement among raft nodes before linearized reading' (duration: 487.497141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:34:41.75647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:34:41.268581Z","time spent":"487.874587ms","remote":"127.0.0.1:43142","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-10-14T19:34:41.903206Z","caller":"traceutil/trace.go:171","msg":"trace[1414561897] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"135.811535ms","start":"2026-10-14T19:34:41.767373Z","end":"2026-10-14T19:34:41.903185Z","steps":["trace[1414561897] 'read index received' (duration: 116.799338ms)","trace[1414561897] 'applied index is now lower than readState.Index' (duration: 19.011287ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:34:41.903314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.928117ms","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":"info","ts":"2026-10-14T19:34:41.903376Z","caller":"traceutil/trace.go:171","msg":"trace[2045927777] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:615; }","duration":"136.031289ms","start":"2026-10-14T19:34:41.767313Z","end":"2026-10-14T19:34:41.903344Z","steps":["trace[2045927777] 'agreement among raft nodes before linearized reading' (duration: 135.953968ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:41.903392Z","caller":"traceutil/trace.go:171","msg":"trace[1885206464] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"142.852793ms","start":"2026-10-14T19:34:41.760517Z","end":"2026-10-14T19:34:41.903369Z","steps":["trace[1885206464] 'process raft request' (duration: 142.614659ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:34:41.903384Z","caller":"traceutil/trace.go:171","msg":"trace[1099444748] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"142.843932ms","start":"2026-10-14T19:34:41.760516Z","end":"2026-10-14T19:34:41.90336Z","steps":["trace[1099444748] 'process raft request' (duration: 123.691933ms)","trace[1099444748] 'compare' (duration: 18.796973ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:34:43.972247Z","caller":"traceutil/trace.go:171","msg":"trace[913011263] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"210.644669ms","start":"2026-10-14T19:34:43.761576Z","end":"2026-10-14T19:34:43.972221Z","steps":["trace[913011263] 'process raft request' (duration: 167.055739ms)","trace[913011263] 'compare' (duration: 43.478038ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:34:43.977375Z","caller":"traceutil/trace.go:171","msg":"trace[1378392117] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"200.886088ms","start":"2026-10-14T19:34:43.776388Z","end":"2026-10-14T19:34:43.977274Z","steps":["trace[1378392117] 'process raft request' (duration: 200.708955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:34:57.554411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.716134ms","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-10-14T19:34:57.554573Z","caller":"traceutil/trace.go:171","msg":"trace[1021662818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:777; }","duration":"104.972278ms","start":"2026-10-14T19:34:57.449574Z","end":"2026-10-14T19:34:57.554547Z","steps":["trace[1021662818] 'range keys from in-memory index tree' (duration: 104.441019ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:35:26.332329Z","caller":"traceutil/trace.go:171","msg":"trace[1157742133] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"101.355069ms","start":"2026-10-14T19:35:26.230951Z","end":"2026-10-14T19:35:26.332306Z","steps":["trace[1157742133] 'process raft request' (duration: 101.144886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:35:26.690234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.225757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T19:35:26.690361Z","caller":"traceutil/trace.go:171","msg":"trace[130134565] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"353.354627ms","start":"2026-10-14T19:35:26.336986Z","end":"2026-10-14T19:35:26.690341Z","steps":["trace[130134565] 'process raft request' (duration: 21.893126ms)","trace[130134565] 'compare' (duration: 330.941524ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:35:26.690433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:35:26.336966Z","time spent":"353.425347ms","remote":"127.0.0.1:43142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T19:35:26.698277Z","caller":"traceutil/trace.go:171","msg":"trace[1837696231] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1218; }","duration":"350.386055ms","start":"2026-10-14T19:35:26.347851Z","end":"2026-10-14T19:35:26.698237Z","steps":["trace[1837696231] 'read index received' (duration: 11.040144ms)","trace[1837696231] 'applied index is now lower than readState.Index' (duration: 339.344911ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:35:26.698353Z","caller":"traceutil/trace.go:171","msg":"trace[1467505701] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"360.600158ms","start":"2026-10-14T19:35:26.337734Z","end":"2026-10-14T19:35:26.698334Z","steps":["trace[1467505701] 'process raft request' (duration: 360.344265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:35:26.698474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:35:26.337723Z","time spent":"360.682269ms","remote":"127.0.0.1:43142","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-10-14T19:35:26.69849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.636628ms","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-10-14T19:35:26.698529Z","caller":"traceutil/trace.go:171","msg":"trace[2043560545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1178; }","duration":"350.71037ms","start":"2026-10-14T19:35:26.347801Z","end":"2026-10-14T19:35:26.698511Z","steps":["trace[2043560545] 'agreement among raft nodes before linearized reading' (duration: 350.537697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:35:26.698569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:35:26.347783Z","time spent":"350.77311ms","remote":"127.0.0.1:43142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-14T19:36:01.56458Z","caller":"traceutil/trace.go:171","msg":"trace[832560456] transaction","detail":"{read_only:false; response_revision:1666; number_of_response:1; }","duration":"121.802351ms","start":"2026-10-14T19:36:01.442751Z","end":"2026-10-14T19:36:01.564553Z","steps":["trace[832560456] 'process raft request' (duration: 121.625378ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:36:01.939264Z","caller":"traceutil/trace.go:171","msg":"trace[1529009635] transaction","detail":"{read_only:false; response_revision:1669; number_of_response:1; }","duration":"161.475233ms","start":"2026-10-14T19:36:01.777768Z","end":"2026-10-14T19:36:01.939243Z","steps":["trace[1529009635] 'process raft request' (duration: 94.5841ms)","trace[1529009635] 'compare' (duration: 66.488347ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:36:15.536802Z","caller":"traceutil/trace.go:171","msg":"trace[1888192779] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1901; }","duration":"202.42748ms","start":"2026-10-14T19:36:15.334317Z","end":"2026-10-14T19:36:15.536744Z","steps":["trace[1888192779] 'read index received' (duration: 178.358217ms)","trace[1888192779] 'applied index is now lower than readState.Index' (duration: 24.067613ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:36:15.536824Z","caller":"traceutil/trace.go:171","msg":"trace[641058731] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"204.678889ms","start":"2026-10-14T19:36:15.332099Z","end":"2026-10-14T19:36:15.536778Z","steps":["trace[641058731] 'process raft request' (duration: 180.731817ms)","trace[641058731] 'compare' (duration: 23.615338ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:36:15.53726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.900556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:36:15.537418Z","caller":"traceutil/trace.go:171","msg":"trace[1731038061] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:1823; }","duration":"203.093848ms","start":"2026-10-14T19:36:15.334276Z","end":"2026-10-14T19:36:15.53737Z","steps":["trace[1731038061] 'agreement among raft nodes before linearized reading' (duration: 202.741934ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:36:15.729322Z","caller":"traceutil/trace.go:171","msg":"trace[1322650299] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"186.754853ms","start":"2026-10-14T19:36:15.542536Z","end":"2026-10-14T19:36:15.729291Z","steps":["trace[1322650299] 'process raft request' (duration: 110.015566ms)","trace[1322650299] 'compare' (duration: 76.405413ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:36:15.729365Z","caller":"traceutil/trace.go:171","msg":"trace[1931516593] linearizableReadLoop","detail":"{readStateIndex:1905; appliedIndex:1902; }","duration":"185.094962ms","start":"2026-10-14T19:36:15.544243Z","end":"2026-10-14T19:36:15.729338Z","steps":["trace[1931516593] 'read index received' (duration: 108.419946ms)","trace[1931516593] 'applied index is now lower than readState.Index' (duration: 76.673526ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:36:15.729471Z","caller":"traceutil/trace.go:171","msg":"trace[1465582435] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"185.784311ms","start":"2026-10-14T19:36:15.543665Z","end":"2026-10-14T19:36:15.729449Z","steps":["trace[1465582435] 'process raft request' (duration: 185.612099ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:36:15.729526Z","caller":"traceutil/trace.go:171","msg":"trace[2065481012] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"185.926023ms","start":"2026-10-14T19:36:15.543571Z","end":"2026-10-14T19:36:15.729497Z","steps":["trace[2065481012] 'process raft request' (duration: 185.63253ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:36:15.729658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.400836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:105345"} {"level":"info","ts":"2026-10-14T19:36:15.729752Z","caller":"traceutil/trace.go:171","msg":"trace[1958376592] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1826; }","duration":"185.534568ms","start":"2026-10-14T19:36:15.544201Z","end":"2026-10-14T19:36:15.729736Z","steps":["trace[1958376592] 'agreement among raft nodes before linearized reading' (duration: 185.242365ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:36:18.336955Z","caller":"traceutil/trace.go:171","msg":"trace[1062645293] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"244.943193ms","start":"2026-10-14T19:36:18.091985Z","end":"2026-10-14T19:36:18.336928Z","steps":["trace[1062645293] 'read index received' (duration: 244.68669ms)","trace[1062645293] 'applied index is now lower than readState.Index' (duration: 255.263µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:36:18.337044Z","caller":"traceutil/trace.go:171","msg":"trace[1290288608] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"252.454227ms","start":"2026-10-14T19:36:18.084536Z","end":"2026-10-14T19:36:18.336991Z","steps":["trace[1290288608] 'process raft request' (duration: 252.208074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:36:18.337214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.217427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4c0e4a64-04ab-4dc8-a8b2-331aeb0cfd51\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-14T19:36:18.337332Z","caller":"traceutil/trace.go:171","msg":"trace[1388082986] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4c0e4a64-04ab-4dc8-a8b2-331aeb0cfd51; range_end:; response_count:1; response_revision:1864; }","duration":"245.399579ms","start":"2026-10-14T19:36:18.091918Z","end":"2026-10-14T19:36:18.337317Z","steps":["trace[1388082986] 'agreement among raft nodes before linearized reading' (duration: 245.155496ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:36:18.510919Z","caller":"traceutil/trace.go:171","msg":"trace[1086669397] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"165.399866ms","start":"2026-10-14T19:36:18.345472Z","end":"2026-10-14T19:36:18.510872Z","steps":["trace[1086669397] 'process raft request' (duration: 153.163453ms)","trace[1086669397] 'compare' (duration: 12.009541ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:36:18.561408Z","caller":"traceutil/trace.go:171","msg":"trace[302147512] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"105.637976ms","start":"2026-10-14T19:36:18.455734Z","end":"2026-10-14T19:36:18.561372Z","steps":["trace[302147512] 'process raft request' (duration: 105.461084ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:36:18.884494Z","caller":"traceutil/trace.go:171","msg":"trace[1989093029] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"284.641641ms","start":"2026-10-14T19:36:18.599824Z","end":"2026-10-14T19:36:18.884466Z","steps":["trace[1989093029] 'process raft request' (duration: 272.406197ms)","trace[1989093029] 'compare' (duration: 11.748157ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:36:31.204054Z","caller":"traceutil/trace.go:171","msg":"trace[1083795375] linearizableReadLoop","detail":"{readStateIndex:2057; appliedIndex:2056; }","duration":"226.14319ms","start":"2026-10-14T19:36:30.97789Z","end":"2026-10-14T19:36:31.204033Z","steps":["trace[1083795375] 'read index received' (duration: 225.405841ms)","trace[1083795375] 'applied index is now lower than readState.Index' (duration: 736.409µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:36:31.204071Z","caller":"traceutil/trace.go:171","msg":"trace[696108935] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"236.497038ms","start":"2026-10-14T19:36:30.967555Z","end":"2026-10-14T19:36:31.204052Z","steps":["trace[696108935] 'process raft request' (duration: 235.728949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:36:31.204347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.431503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-14T19:36:31.204461Z","caller":"traceutil/trace.go:171","msg":"trace[936365100] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1975; }","duration":"226.571275ms","start":"2026-10-14T19:36:30.977863Z","end":"2026-10-14T19:36:31.204434Z","steps":["trace[936365100] 'agreement among raft nodes before linearized reading' (duration: 226.277531ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:36:31.207505Z","caller":"traceutil/trace.go:171","msg":"trace[1868760272] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"209.784797ms","start":"2026-10-14T19:36:30.997704Z","end":"2026-10-14T19:36:31.207489Z","steps":["trace[1868760272] 'process raft request' (duration: 209.100929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:36:31.207662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.714859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T19:36:31.207663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.683218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-14T19:36:31.207694Z","caller":"traceutil/trace.go:171","msg":"trace[444222142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1977; }","duration":"103.77648ms","start":"2026-10-14T19:36:31.103906Z","end":"2026-10-14T19:36:31.207683Z","steps":["trace[444222142] 'agreement among raft nodes before linearized reading' (duration: 103.703769ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:36:31.207711Z","caller":"traceutil/trace.go:171","msg":"trace[963533906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1977; }","duration":"150.75428ms","start":"2026-10-14T19:36:31.056943Z","end":"2026-10-14T19:36:31.207697Z","steps":["trace[963533906] 'agreement among raft nodes before linearized reading' (duration: 150.585098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:36:31.207932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.767441ms","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-10-14T19:36:31.207959Z","caller":"traceutil/trace.go:171","msg":"trace[1870870085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1977; }","duration":"176.818361ms","start":"2026-10-14T19:36:31.031132Z","end":"2026-10-14T19:36:31.20795Z","steps":["trace[1870870085] 'agreement among raft nodes before linearized reading' (duration: 176.73978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:36:31.208175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.989842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11986"} {"level":"info","ts":"2026-10-14T19:36:31.20824Z","caller":"traceutil/trace.go:171","msg":"trace[2103646602] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1977; }","duration":"202.078722ms","start":"2026-10-14T19:36:31.006143Z","end":"2026-10-14T19:36:31.208222Z","steps":["trace[2103646602] 'agreement among raft nodes before linearized reading' (duration: 201.07475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:36:53.562637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.155154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T19:36:53.562751Z","caller":"traceutil/trace.go:171","msg":"trace[758191432] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"204.319895ms","start":"2026-10-14T19:36:53.358413Z","end":"2026-10-14T19:36:53.562733Z","steps":["trace[758191432] 'read index received' (duration: 140.722µs)","trace[758191432] 'applied index is now lower than readState.Index' (duration: 204.177653ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:36:53.563285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.885881ms","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-10-14T19:36:53.563332Z","caller":"traceutil/trace.go:171","msg":"trace[761504578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2106; }","duration":"204.997123ms","start":"2026-10-14T19:36:53.358321Z","end":"2026-10-14T19:36:53.563318Z","steps":["trace[761504578] 'agreement among raft nodes before linearized reading' (duration: 204.837501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:36:53.563384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.231639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-10-14T19:36:53.56351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.355211ms","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-10-14T19:36:53.563741Z","caller":"traceutil/trace.go:171","msg":"trace[497767194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2106; }","duration":"202.617323ms","start":"2026-10-14T19:36:53.361087Z","end":"2026-10-14T19:36:53.563705Z","steps":["trace[497767194] 'agreement among raft nodes before linearized reading' (duration: 202.101097ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:36:53.563005Z","caller":"traceutil/trace.go:171","msg":"trace[1712228506] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"215.308337ms","start":"2026-10-14T19:36:53.347684Z","end":"2026-10-14T19:36:53.562992Z","steps":["trace[1712228506] 'compare' (duration: 205.056843ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:36:53.563828Z","caller":"traceutil/trace.go:171","msg":"trace[2002261337] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2106; }","duration":"202.493842ms","start":"2026-10-14T19:36:53.361074Z","end":"2026-10-14T19:36:53.563568Z","steps":["trace[2002261337] 'agreement among raft nodes before linearized reading' (duration: 202.119507ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:36:53.857776Z","caller":"traceutil/trace.go:171","msg":"trace[1830692222] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"287.787301ms","start":"2026-10-14T19:36:53.569967Z","end":"2026-10-14T19:36:53.857754Z","steps":["trace[1830692222] 'process raft request' (duration: 287.506698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:36:53.858188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.784291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-10-14T19:36:53.858239Z","caller":"traceutil/trace.go:171","msg":"trace[1387638657] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2108; }","duration":"214.903752ms","start":"2026-10-14T19:36:53.643323Z","end":"2026-10-14T19:36:53.858227Z","steps":["trace[1387638657] 'agreement among raft nodes before linearized reading' (duration: 214.713099ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:36:53.859356Z","caller":"traceutil/trace.go:171","msg":"trace[1462550712] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2193; }","duration":"214.238475ms","start":"2026-10-14T19:36:53.643395Z","end":"2026-10-14T19:36:53.857634Z","steps":["trace[1462550712] 'read index received' (duration: 212.918979ms)","trace[1462550712] 'applied index is now lower than readState.Index' (duration: 1.318536ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:36:54.167203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.799599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T19:36:54.167362Z","caller":"traceutil/trace.go:171","msg":"trace[1210695757] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"395.690431ms","start":"2026-10-14T19:36:53.77165Z","end":"2026-10-14T19:36:54.16734Z","steps":["trace[1210695757] 'process raft request' (duration: 204.661599ms)","trace[1210695757] 'compare' (duration: 190.709808ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:36:54.167444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:36:53.771619Z","time spent":"395.783682ms","remote":"127.0.0.1:43142","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-10-14T19:38:01.169972Z","caller":"traceutil/trace.go:171","msg":"trace[162728946] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"266.643764ms","start":"2026-10-14T19:38:00.903301Z","end":"2026-10-14T19:38:01.169944Z","steps":["trace[162728946] 'process raft request' (duration: 266.484049ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:38:01.17739Z","caller":"traceutil/trace.go:171","msg":"trace[1592578847] transaction","detail":"{read_only:false; response_revision:2569; number_of_response:1; }","duration":"183.146204ms","start":"2026-10-14T19:38:00.994217Z","end":"2026-10-14T19:38:01.177363Z","steps":["trace[1592578847] 'process raft request' (duration: 182.676701ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:38:33.431425Z","caller":"traceutil/trace.go:171","msg":"trace[1745072146] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"136.170155ms","start":"2026-10-14T19:38:33.295224Z","end":"2026-10-14T19:38:33.431394Z","steps":["trace[1745072146] 'process raft request' (duration: 135.95618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:38:33.757217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.931243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:38:33.757325Z","caller":"traceutil/trace.go:171","msg":"trace[1640238330] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2749; }","duration":"237.062746ms","start":"2026-10-14T19:38:33.520243Z","end":"2026-10-14T19:38:33.757306Z","steps":["trace[1640238330] 'count revisions from in-memory index tree' (duration: 236.835281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:38:33.757661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.228047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-14T19:38:33.757763Z","caller":"traceutil/trace.go:171","msg":"trace[1562318027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2749; }","duration":"108.3709ms","start":"2026-10-14T19:38:33.64938Z","end":"2026-10-14T19:38:33.75775Z","steps":["trace[1562318027] 'range keys from in-memory index tree' (duration: 107.839698ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:38:39.04408Z","caller":"traceutil/trace.go:171","msg":"trace[988159057] linearizableReadLoop","detail":"{readStateIndex:2888; appliedIndex:2887; }","duration":"128.679024ms","start":"2026-10-14T19:38:38.91538Z","end":"2026-10-14T19:38:39.044059Z","steps":["trace[988159057] 'read index received' (duration: 128.47943ms)","trace[988159057] 'applied index is now lower than readState.Index' (duration: 198.254µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:38:39.044215Z","caller":"traceutil/trace.go:171","msg":"trace[1295131567] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"204.328924ms","start":"2026-10-14T19:38:38.839875Z","end":"2026-10-14T19:38:39.044204Z","steps":["trace[1295131567] 'process raft request' (duration: 204.020556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:38:39.044609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.107524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:38:39.044682Z","caller":"traceutil/trace.go:171","msg":"trace[1021311681] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:2778; }","duration":"129.35331ms","start":"2026-10-14T19:38:38.915315Z","end":"2026-10-14T19:38:39.044668Z","steps":["trace[1021311681] 'agreement among raft nodes before linearized reading' (duration: 129.134624ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:47.374389Z","caller":"traceutil/trace.go:171","msg":"trace[1785676898] transaction","detail":"{read_only:false; response_revision:4258; number_of_response:1; }","duration":"102.996836ms","start":"2026-10-14T19:42:47.271372Z","end":"2026-10-14T19:42:47.374369Z","steps":["trace[1785676898] 'process raft request' (duration: 102.879464ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:47.37443Z","caller":"traceutil/trace.go:171","msg":"trace[385690249] transaction","detail":"{read_only:false; response_revision:4257; number_of_response:1; }","duration":"103.053217ms","start":"2026-10-14T19:42:47.271346Z","end":"2026-10-14T19:42:47.3744Z","steps":["trace[385690249] 'process raft request' (duration: 102.809443ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:47.374502Z","caller":"traceutil/trace.go:171","msg":"trace[31140311] transaction","detail":"{read_only:false; response_revision:4259; number_of_response:1; }","duration":"101.337431ms","start":"2026-10-14T19:42:47.2731Z","end":"2026-10-14T19:42:47.374437Z","steps":["trace[31140311] 'process raft request' (duration: 101.205819ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:54.875669Z","caller":"traceutil/trace.go:171","msg":"trace[180484822] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"256.728095ms","start":"2026-10-14T19:42:54.618918Z","end":"2026-10-14T19:42:54.875646Z","steps":["trace[180484822] 'process raft request' (duration: 186.357787ms)","trace[180484822] 'compare' (duration: 69.963432ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:42:54.893896Z","caller":"traceutil/trace.go:171","msg":"trace[945076670] linearizableReadLoop","detail":"{readStateIndex:4812; appliedIndex:4810; }","duration":"193.147671ms","start":"2026-10-14T19:42:54.700724Z","end":"2026-10-14T19:42:54.893872Z","steps":["trace[945076670] 'read index received' (duration: 104.132336ms)","trace[945076670] 'applied index is now lower than readState.Index' (duration: 89.014225ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:42:54.894033Z","caller":"traceutil/trace.go:171","msg":"trace[1288886707] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"234.339011ms","start":"2026-10-14T19:42:54.659651Z","end":"2026-10-14T19:42:54.89399Z","steps":["trace[1288886707] 'process raft request' (duration: 234.058967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:42:54.89405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.312383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:42:54.894159Z","caller":"traceutil/trace.go:171","msg":"trace[22235761] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls; range_end:; response_count:0; response_revision:4625; }","duration":"193.438625ms","start":"2026-10-14T19:42:54.700708Z","end":"2026-10-14T19:42:54.894146Z","steps":["trace[22235761] 'agreement among raft nodes before linearized reading' (duration: 193.287772ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:57.1015Z","caller":"traceutil/trace.go:171","msg":"trace[1426331788] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"135.613696ms","start":"2026-10-14T19:42:56.965864Z","end":"2026-10-14T19:42:57.101478Z","steps":["trace[1426331788] 'process raft request' (duration: 135.371782ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:57.101713Z","caller":"traceutil/trace.go:171","msg":"trace[1530854545] linearizableReadLoop","detail":"{readStateIndex:4871; appliedIndex:4870; }","duration":"135.742047ms","start":"2026-10-14T19:42:56.965916Z","end":"2026-10-14T19:42:57.101658Z","steps":["trace[1530854545] 'read index received' (duration: 17.413017ms)","trace[1530854545] 'applied index is now lower than readState.Index' (duration: 118.32803ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:42:57.101651Z","caller":"traceutil/trace.go:171","msg":"trace[1679682860] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"135.696147ms","start":"2026-10-14T19:42:56.965917Z","end":"2026-10-14T19:42:57.101613Z","steps":["trace[1679682860] 'process raft request' (duration: 135.442213ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:42:57.101796Z","caller":"traceutil/trace.go:171","msg":"trace[1281936203] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"135.176369ms","start":"2026-10-14T19:42:56.96659Z","end":"2026-10-14T19:42:57.101766Z","steps":["trace[1281936203] 'process raft request' (duration: 134.830604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:42:57.101871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.93666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-14T19:42:57.102312Z","caller":"traceutil/trace.go:171","msg":"trace[1461946944] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4685; }","duration":"136.408677ms","start":"2026-10-14T19:42:56.965892Z","end":"2026-10-14T19:42:57.102301Z","steps":["trace[1461946944] 'agreement among raft nodes before linearized reading' (duration: 135.839478ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:43:03.393977Z","caller":"traceutil/trace.go:171","msg":"trace[1803858261] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"123.149447ms","start":"2026-10-14T19:43:03.270807Z","end":"2026-10-14T19:43:03.393957Z","steps":["trace[1803858261] 'process raft request' (duration: 123.027045ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:43:03.443377Z","caller":"traceutil/trace.go:171","msg":"trace[1926260626] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"144.639544ms","start":"2026-10-14T19:43:03.298694Z","end":"2026-10-14T19:43:03.443333Z","steps":["trace[1926260626] 'process raft request' (duration: 108.882019ms)","trace[1926260626] 'compare' (duration: 35.627953ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:43:08.778202Z","caller":"traceutil/trace.go:171","msg":"trace[1720456460] linearizableReadLoop","detail":"{readStateIndex:5150; appliedIndex:5149; }","duration":"177.005269ms","start":"2026-10-14T19:43:08.601172Z","end":"2026-10-14T19:43:08.778178Z","steps":["trace[1720456460] 'read index received' (duration: 176.852787ms)","trace[1720456460] 'applied index is now lower than readState.Index' (duration: 151.492µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:43:08.778241Z","caller":"traceutil/trace.go:171","msg":"trace[1195960540] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"216.872836ms","start":"2026-10-14T19:43:08.561338Z","end":"2026-10-14T19:43:08.778211Z","steps":["trace[1195960540] 'process raft request' (duration: 216.687373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:43:08.778462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.270633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b723ef48-d14c-4b3f-9e14-d9b3e00350db\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-14T19:43:08.778499Z","caller":"traceutil/trace.go:171","msg":"trace[1133336289] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b723ef48-d14c-4b3f-9e14-d9b3e00350db; range_end:; response_count:1; response_revision:4955; }","duration":"177.349455ms","start":"2026-10-14T19:43:08.601137Z","end":"2026-10-14T19:43:08.778486Z","steps":["trace[1133336289] 'agreement among raft nodes before linearized reading' (duration: 177.171852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:43:08.778519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.530237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-14T19:43:08.77855Z","caller":"traceutil/trace.go:171","msg":"trace[1395797897] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4955; }","duration":"175.585288ms","start":"2026-10-14T19:43:08.602956Z","end":"2026-10-14T19:43:08.778541Z","steps":["trace[1395797897] 'agreement among raft nodes before linearized reading' (duration: 175.457546ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:43:09.084757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.453305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-14T19:43:09.084828Z","caller":"traceutil/trace.go:171","msg":"trace[1873007367] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4955; }","duration":"300.546506ms","start":"2026-10-14T19:43:08.784265Z","end":"2026-10-14T19:43:09.084811Z","steps":["trace[1873007367] 'range keys from in-memory index tree' (duration: 300.314973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:43:09.084863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:43:08.784252Z","time spent":"300.602287ms","remote":"127.0.0.1:43066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-14T19:43:09.504852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.95051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T19:43:09.505026Z","caller":"traceutil/trace.go:171","msg":"trace[381906450] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:4959; }","duration":"112.260664ms","start":"2026-10-14T19:43:09.392743Z","end":"2026-10-14T19:43:09.505003Z","steps":["trace[381906450] 'count revisions from in-memory index tree' (duration: 111.855398ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:43:13.856065Z","caller":"traceutil/trace.go:171","msg":"trace[1149341484] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"109.461189ms","start":"2026-10-14T19:43:13.74658Z","end":"2026-10-14T19:43:13.856041Z","steps":["trace[1149341484] 'process raft request' (duration: 108.908371ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:43:25.243354Z","caller":"traceutil/trace.go:171","msg":"trace[1847053782] linearizableReadLoop","detail":"{readStateIndex:5368; appliedIndex:5367; }","duration":"138.624777ms","start":"2026-10-14T19:43:25.104692Z","end":"2026-10-14T19:43:25.243317Z","steps":["trace[1847053782] 'read index received' (duration: 138.346663ms)","trace[1847053782] 'applied index is now lower than readState.Index' (duration: 276.544µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:43:25.243551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.84091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:43:25.243494Z","caller":"traceutil/trace.go:171","msg":"trace[27818210] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"401.114429ms","start":"2026-10-14T19:43:24.842335Z","end":"2026-10-14T19:43:25.24345Z","steps":["trace[27818210] 'process raft request' (duration: 400.742834ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:43:25.243597Z","caller":"traceutil/trace.go:171","msg":"trace[222632284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5165; }","duration":"138.957872ms","start":"2026-10-14T19:43:25.104628Z","end":"2026-10-14T19:43:25.243586Z","steps":["trace[222632284] 'agreement among raft nodes before linearized reading' (duration: 138.82378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:43:25.243889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T19:43:24.842137Z","time spent":"401.635977ms","remote":"127.0.0.1:43142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T19:43:25.400999Z","caller":"traceutil/trace.go:171","msg":"trace[1361156800] linearizableReadLoop","detail":"{readStateIndex:5370; appliedIndex:5368; }","duration":"155.533181ms","start":"2026-10-14T19:43:25.245439Z","end":"2026-10-14T19:43:25.400972Z","steps":["trace[1361156800] 'read index received' (duration: 144.326853ms)","trace[1361156800] 'applied index is now lower than readState.Index' (duration: 11.205488ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:43:25.401106Z","caller":"traceutil/trace.go:171","msg":"trace[140221909] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"228.808284ms","start":"2026-10-14T19:43:25.172286Z","end":"2026-10-14T19:43:25.401095Z","steps":["trace[140221909] 'process raft request' (duration: 228.639862ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:43:25.401095Z","caller":"traceutil/trace.go:171","msg":"trace[763461641] transaction","detail":"{read_only:false; response_revision:5166; number_of_response:1; }","duration":"288.32693ms","start":"2026-10-14T19:43:25.112717Z","end":"2026-10-14T19:43:25.401044Z","steps":["trace[763461641] 'process raft request' (duration: 277.03149ms)","trace[763461641] 'compare' (duration: 11.056287ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T19:43:25.401641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.018938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:43:25.401879Z","caller":"traceutil/trace.go:171","msg":"trace[1136177182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5167; }","duration":"156.484196ms","start":"2026-10-14T19:43:25.245379Z","end":"2026-10-14T19:43:25.401706Z","steps":["trace[1136177182] 'agreement among raft nodes before linearized reading' (duration: 156.035329ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:43:25.408034Z","caller":"traceutil/trace.go:171","msg":"trace[560794587] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"160.692119ms","start":"2026-10-14T19:43:25.247315Z","end":"2026-10-14T19:43:25.408007Z","steps":["trace[560794587] 'process raft request' (duration: 160.564737ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T19:43:50.996541Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2843} {"level":"info","ts":"2026-10-14T19:43:51.080901Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2843,"took":"83.044325ms","hash":35385594,"current-db-size-bytes":52224000,"current-db-size":"52 MB","current-db-size-in-use-bytes":27385856,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-14T19:43:51.080956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":35385594,"revision":2843,"compact-revision":-1} {"level":"warn","ts":"2026-10-14T19:44:06.670587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.72944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T19:44:06.670676Z","caller":"traceutil/trace.go:171","msg":"trace[1903971844] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5510; }","duration":"142.870891ms","start":"2026-10-14T19:44:06.527784Z","end":"2026-10-14T19:44:06.670655Z","steps":["trace[1903971844] 'count revisions from in-memory index tree' (duration: 142.658829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:44:06.67072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.213342ms","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-10-14T19:44:06.670787Z","caller":"traceutil/trace.go:171","msg":"trace[1306588426] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5510; }","duration":"299.335304ms","start":"2026-10-14T19:44:06.371433Z","end":"2026-10-14T19:44:06.670768Z","steps":["trace[1306588426] 'range keys from in-memory index tree' (duration: 299.04709ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T19:47:13.45902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.960989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T19:47:13.459437Z","caller":"traceutil/trace.go:171","msg":"trace[416980029] transaction","detail":"{read_only:false; response_revision:6801; number_of_response:1; }","duration":"134.359827ms","start":"2026-10-14T19:47:13.324777Z","end":"2026-10-14T19:47:13.459137Z","steps":["trace[416980029] 'process raft request' (duration: 32.161981ms)","trace[416980029] 'compare' (duration: 101.429735ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T19:48:51.003831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5330} {"level":"info","ts":"2026-10-14T19:48:51.109296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5330,"took":"103.689759ms","hash":1963572520,"current-db-size-bytes":52224000,"current-db-size":"52 MB","current-db-size-in-use-bytes":18399232,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-14T19:48:51.109338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1963572520,"revision":5330,"compact-revision":2843} {"level":"info","ts":"2026-10-14T19:53:51.008939Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7373} {"level":"info","ts":"2026-10-14T19:53:51.065428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7373,"took":"55.411639ms","hash":4073578834,"current-db-size-bytes":52224000,"current-db-size":"52 MB","current-db-size-in-use-bytes":15151104,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-10-14T19:53:51.065474Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4073578834,"revision":7373,"compact-revision":5330}