{"level":"warn","ts":"2026-09-16T15:50:05.497539Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-16T15:50:05.497641Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.75:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.75:2380","--initial-cluster=instance=https://162.253.55.75:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.75:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.75:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-16T15:50:05.497723Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-16T15:50:05.497734Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.75:2380"]} {"level":"info","ts":"2026-09-16T15:50:05.497752Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-16T15:50:05.498559Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.75:2379"]} {"level":"info","ts":"2026-09-16T15:50:05.498654Z","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://162.253.55.75:2380"],"listen-peer-urls":["https://162.253.55.75:2380"],"advertise-client-urls":["https://162.253.55.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.75:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-16T15:50:05.504159Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.222731ms"} {"level":"info","ts":"2026-09-16T15:50:05.514286Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"871515da6373d131","cluster-id":"1ca87f0850671e76"} {"level":"info","ts":"2026-09-16T15:50:05.514383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 switched to configuration voters=()"} {"level":"info","ts":"2026-09-16T15:50:05.514409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 became follower at term 0"} {"level":"info","ts":"2026-09-16T15:50:05.514422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 871515da6373d131 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-16T15:50:05.514433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 became follower at term 1"} {"level":"info","ts":"2026-09-16T15:50:05.514678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 switched to configuration voters=(9733710197346783537)"} {"level":"warn","ts":"2026-09-16T15:50:05.52268Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-16T15:50:05.524465Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-16T15:50:05.526022Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-16T15:50:05.528471Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"871515da6373d131","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-16T15:50:05.528665Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"871515da6373d131","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-16T15:50:05.529949Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-16T15:50:05.530033Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-16T15:50:05.530045Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-16T15:50:05.531156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 switched to configuration voters=(9733710197346783537)"} {"level":"info","ts":"2026-09-16T15:50:05.531302Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1ca87f0850671e76","local-member-id":"871515da6373d131","added-peer-id":"871515da6373d131","added-peer-peer-urls":["https://162.253.55.75:2380"]} {"level":"info","ts":"2026-09-16T15:50:05.533352Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-16T15:50:05.533541Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.75:2380"} {"level":"info","ts":"2026-09-16T15:50:05.533608Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.75:2380"} {"level":"info","ts":"2026-09-16T15:50:05.533779Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"871515da6373d131","initial-advertise-peer-urls":["https://162.253.55.75:2380"],"listen-peer-urls":["https://162.253.55.75:2380"],"advertise-client-urls":["https://162.253.55.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-16T15:50:05.533844Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-16T15:50:05.915385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 is starting a new election at term 1"} {"level":"info","ts":"2026-09-16T15:50:05.915432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-16T15:50:05.915456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 received MsgPreVoteResp from 871515da6373d131 at term 1"} {"level":"info","ts":"2026-09-16T15:50:05.91547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 became candidate at term 2"} {"level":"info","ts":"2026-09-16T15:50:05.915477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 received MsgVoteResp from 871515da6373d131 at term 2"} {"level":"info","ts":"2026-09-16T15:50:05.915487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 became leader at term 2"} {"level":"info","ts":"2026-09-16T15:50:05.915495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 871515da6373d131 elected leader 871515da6373d131 at term 2"} {"level":"info","ts":"2026-09-16T15:50:05.916338Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T15:50:05.917294Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"871515da6373d131","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.75:2379]}","request-path":"/0/members/871515da6373d131/attributes","cluster-id":"1ca87f0850671e76","publish-timeout":"7s"} {"level":"info","ts":"2026-09-16T15:50:05.917364Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-16T15:50:05.917389Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-16T15:50:05.917942Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1ca87f0850671e76","local-member-id":"871515da6373d131","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T15:50:05.918007Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T15:50:05.918024Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T15:50:05.919044Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.75:2379"} {"level":"info","ts":"2026-09-16T15:50:05.919611Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-16T15:50:05.919665Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-16T15:50:05.919892Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-16T15:50:10.751796Z","caller":"traceutil/trace.go:171","msg":"trace[1440218236] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"136.907792ms","start":"2026-09-16T15:50:10.614851Z","end":"2026-09-16T15:50:10.751758Z","steps":["trace[1440218236] 'process raft request' (duration: 78.010478ms)","trace[1440218236] 'compare' (duration: 58.636682ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:50:10.751978Z","caller":"traceutil/trace.go:171","msg":"trace[297885850] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"132.586356ms","start":"2026-09-16T15:50:10.619362Z","end":"2026-09-16T15:50:10.751949Z","steps":["trace[297885850] 'process raft request' (duration: 132.308085ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:10.941985Z","caller":"traceutil/trace.go:171","msg":"trace[1858047809] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"107.118796ms","start":"2026-09-16T15:50:10.834835Z","end":"2026-09-16T15:50:10.941953Z","steps":["trace[1858047809] 'process raft request' (duration: 41.847224ms)","trace[1858047809] 'compare' (duration: 64.662219ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:50:10.942484Z","caller":"traceutil/trace.go:171","msg":"trace[225130811] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:270; }","duration":"102.165758ms","start":"2026-09-16T15:50:10.840295Z","end":"2026-09-16T15:50:10.942461Z","steps":["trace[225130811] 'read index received' (duration: 36.391502ms)","trace[225130811] 'applied index is now lower than readState.Index' (duration: 65.772046ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:50:10.942712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.299419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T15:50:10.942776Z","caller":"traceutil/trace.go:171","msg":"trace[249448807] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:263; }","duration":"102.5166ms","start":"2026-09-16T15:50:10.840251Z","end":"2026-09-16T15:50:10.942768Z","steps":["trace[249448807] 'agreement among raft nodes before linearized reading' (duration: 102.296609ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:43.24739Z","caller":"traceutil/trace.go:171","msg":"trace[624948643] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"210.427978ms","start":"2026-09-16T15:50:43.036938Z","end":"2026-09-16T15:50:43.247366Z","steps":["trace[624948643] 'process raft request' (duration: 210.292811ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:43.255505Z","caller":"traceutil/trace.go:171","msg":"trace[1662841321] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"218.446988ms","start":"2026-09-16T15:50:43.037032Z","end":"2026-09-16T15:50:43.255479Z","steps":["trace[1662841321] 'process raft request' (duration: 218.269131ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:43.255589Z","caller":"traceutil/trace.go:171","msg":"trace[720313878] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"211.931057ms","start":"2026-09-16T15:50:43.043605Z","end":"2026-09-16T15:50:43.255537Z","steps":["trace[720313878] 'process raft request' (duration: 211.789432ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:43.25567Z","caller":"traceutil/trace.go:171","msg":"trace[105782305] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"206.603494ms","start":"2026-09-16T15:50:43.049022Z","end":"2026-09-16T15:50:43.255625Z","steps":["trace[105782305] 'process raft request' (duration: 206.455088ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:43.255659Z","caller":"traceutil/trace.go:171","msg":"trace[1252264009] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"207.336913ms","start":"2026-09-16T15:50:43.048217Z","end":"2026-09-16T15:50:43.255554Z","steps":["trace[1252264009] 'process raft request' (duration: 207.215808ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:43.255795Z","caller":"traceutil/trace.go:171","msg":"trace[109159978] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"204.815273ms","start":"2026-09-16T15:50:43.050973Z","end":"2026-09-16T15:50:43.255788Z","steps":["trace[109159978] 'process raft request' (duration: 204.554532ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:43.255826Z","caller":"traceutil/trace.go:171","msg":"trace[359646089] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"201.379535ms","start":"2026-09-16T15:50:43.054431Z","end":"2026-09-16T15:50:43.255811Z","steps":["trace[359646089] 'process raft request' (duration: 201.240178ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:43.255764Z","caller":"traceutil/trace.go:171","msg":"trace[793967011] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"203.2534ms","start":"2026-09-16T15:50:43.052498Z","end":"2026-09-16T15:50:43.255752Z","steps":["trace[793967011] 'process raft request' (duration: 203.068301ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:43.255864Z","caller":"traceutil/trace.go:171","msg":"trace[182314654] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"194.713968ms","start":"2026-09-16T15:50:43.061107Z","end":"2026-09-16T15:50:43.255821Z","steps":["trace[182314654] 'process raft request' (duration: 194.608224ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:43.255935Z","caller":"traceutil/trace.go:171","msg":"trace[27660231] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"183.181436ms","start":"2026-09-16T15:50:43.07274Z","end":"2026-09-16T15:50:43.255921Z","steps":["trace[27660231] 'process raft request' (duration: 183.02523ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:43.256015Z","caller":"traceutil/trace.go:171","msg":"trace[1475482378] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"151.941295ms","start":"2026-09-16T15:50:43.104054Z","end":"2026-09-16T15:50:43.255995Z","steps":["trace[1475482378] 'process raft request' (duration: 151.760408ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:49.420951Z","caller":"traceutil/trace.go:171","msg":"trace[1774077905] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"148.57494ms","start":"2026-09-16T15:50:49.27232Z","end":"2026-09-16T15:50:49.420895Z","steps":["trace[1774077905] 'process raft request' (duration: 147.594073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:50:49.608516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.297677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T15:50:49.608771Z","caller":"traceutil/trace.go:171","msg":"trace[765044870] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"182.042831ms","start":"2026-09-16T15:50:49.42668Z","end":"2026-09-16T15:50:49.608723Z","steps":["trace[765044870] 'process raft request' (duration: 61.829651ms)","trace[765044870] 'compare' (duration: 119.160421ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:50:49.929479Z","caller":"traceutil/trace.go:171","msg":"trace[169171029] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"417.792639ms","start":"2026-09-16T15:50:49.511662Z","end":"2026-09-16T15:50:49.929455Z","steps":["trace[169171029] 'read index received' (duration: 364.911283ms)","trace[169171029] 'applied index is now lower than readState.Index' (duration: 52.879336ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:50:49.929537Z","caller":"traceutil/trace.go:171","msg":"trace[1752768507] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"431.708468ms","start":"2026-09-16T15:50:49.497794Z","end":"2026-09-16T15:50:49.929503Z","steps":["trace[1752768507] 'process raft request' (duration: 378.917945ms)","trace[1752768507] 'compare' (duration: 52.393028ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:50:49.929757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.08304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-16T15:50:49.929821Z","caller":"traceutil/trace.go:171","msg":"trace[895208024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"418.189954ms","start":"2026-09-16T15:50:49.511613Z","end":"2026-09-16T15:50:49.929803Z","steps":["trace[895208024] 'agreement among raft nodes before linearized reading' (duration: 417.982856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:50:49.929861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:50:49.511596Z","time spent":"418.255495ms","remote":"127.0.0.1:43728","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":"warn","ts":"2026-09-16T15:50:49.930054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.907297ms","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-09-16T15:50:49.930163Z","caller":"traceutil/trace.go:171","msg":"trace[835749953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"378.205409ms","start":"2026-09-16T15:50:49.551942Z","end":"2026-09-16T15:50:49.930147Z","steps":["trace[835749953] 'agreement among raft nodes before linearized reading' (duration: 377.721451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:50:49.930226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.758647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T15:50:49.930273Z","caller":"traceutil/trace.go:171","msg":"trace[1633652159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"194.841739ms","start":"2026-09-16T15:50:49.735418Z","end":"2026-09-16T15:50:49.93026Z","steps":["trace[1633652159] 'agreement among raft nodes before linearized reading' (duration: 194.774897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:50:49.930239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:50:49.551913Z","time spent":"378.310405ms","remote":"127.0.0.1:43728","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-09-16T15:50:49.930597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.373989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ksncz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-16T15:50:49.930702Z","caller":"traceutil/trace.go:171","msg":"trace[1200229164] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ksncz; range_end:; response_count:1; response_revision:589; }","duration":"195.512314ms","start":"2026-09-16T15:50:49.735174Z","end":"2026-09-16T15:50:49.930686Z","steps":["trace[1200229164] 'agreement among raft nodes before linearized reading' (duration: 195.367448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:50:49.929988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:50:49.497769Z","time spent":"432.03642ms","remote":"127.0.0.1:43790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-16T15:50:50.550426Z","caller":"traceutil/trace.go:171","msg":"trace[1556149264] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"178.641288ms","start":"2026-09-16T15:50:50.371722Z","end":"2026-09-16T15:50:50.550364Z","steps":["trace[1556149264] 'process raft request' (duration: 178.087667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:50:52.044369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.334318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-16T15:50:52.044468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.089685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T15:50:52.044527Z","caller":"traceutil/trace.go:171","msg":"trace[836814738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"100.520264ms","start":"2026-09-16T15:50:51.943983Z","end":"2026-09-16T15:50:52.044503Z","steps":["trace[836814738] 'range keys from in-memory index tree' (duration: 100.11385ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:50:52.044527Z","caller":"traceutil/trace.go:171","msg":"trace[1030362680] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:612; }","duration":"130.175197ms","start":"2026-09-16T15:50:51.914312Z","end":"2026-09-16T15:50:52.044511Z","steps":["trace[1030362680] 'range keys from in-memory index tree' (duration: 130.020272ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:51:06.233167Z","caller":"traceutil/trace.go:171","msg":"trace[1479775783] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"108.222994ms","start":"2026-09-16T15:51:06.124923Z","end":"2026-09-16T15:51:06.233146Z","steps":["trace[1479775783] 'read index received' (duration: 108.09513ms)","trace[1479775783] 'applied index is now lower than readState.Index' (duration: 127.164µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:51:06.233215Z","caller":"traceutil/trace.go:171","msg":"trace[366370135] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"114.763347ms","start":"2026-09-16T15:51:06.118434Z","end":"2026-09-16T15:51:06.233197Z","steps":["trace[366370135] 'process raft request' (duration: 114.594432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:51:06.233377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.43219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-16T15:51:06.233425Z","caller":"traceutil/trace.go:171","msg":"trace[825165314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"108.513993ms","start":"2026-09-16T15:51:06.124899Z","end":"2026-09-16T15:51:06.233413Z","steps":["trace[825165314] 'agreement among raft nodes before linearized reading' (duration: 108.374979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:51:06.233473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.748712ms","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-09-16T15:51:06.233593Z","caller":"traceutil/trace.go:171","msg":"trace[867764056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"101.873448ms","start":"2026-09-16T15:51:06.131686Z","end":"2026-09-16T15:51:06.233559Z","steps":["trace[867764056] 'agreement among raft nodes before linearized reading' (duration: 101.684122ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:51:06.436556Z","caller":"traceutil/trace.go:171","msg":"trace[1235683660] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"198.45511ms","start":"2026-09-16T15:51:06.238077Z","end":"2026-09-16T15:51:06.436532Z","steps":["trace[1235683660] 'process raft request' (duration: 198.269105ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:51:06.438863Z","caller":"traceutil/trace.go:171","msg":"trace[229013974] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"200.265566ms","start":"2026-09-16T15:51:06.238582Z","end":"2026-09-16T15:51:06.438848Z","steps":["trace[229013974] 'process raft request' (duration: 200.202153ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:52:26.657413Z","caller":"traceutil/trace.go:171","msg":"trace[402247309] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"396.869021ms","start":"2026-09-16T15:52:26.260516Z","end":"2026-09-16T15:52:26.657385Z","steps":["trace[402247309] 'read index received' (duration: 396.638697ms)","trace[402247309] 'applied index is now lower than readState.Index' (duration: 228.714µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:52:26.65751Z","caller":"traceutil/trace.go:171","msg":"trace[1598497188] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"399.87167ms","start":"2026-09-16T15:52:26.257615Z","end":"2026-09-16T15:52:26.657487Z","steps":["trace[1598497188] 'process raft request' (duration: 399.605565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:52:26.657637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.095104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T15:52:26.657698Z","caller":"traceutil/trace.go:171","msg":"trace[679903507] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1847; }","duration":"397.171905ms","start":"2026-09-16T15:52:26.260513Z","end":"2026-09-16T15:52:26.657685Z","steps":["trace[679903507] 'agreement among raft nodes before linearized reading' (duration: 397.056554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:52:26.657735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:52:26.260504Z","time spent":"397.222036ms","remote":"127.0.0.1:43614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-09-16T15:52:26.657984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:52:26.25759Z","time spent":"400.070042ms","remote":"127.0.0.1:43676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T15:52:26.665609Z","caller":"traceutil/trace.go:171","msg":"trace[166259377] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"385.271292ms","start":"2026-09-16T15:52:26.280296Z","end":"2026-09-16T15:52:26.665567Z","steps":["trace[166259377] 'process raft request' (duration: 385.055349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:52:26.665782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:52:26.280276Z","time spent":"385.421045ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T15:52:26.794262Z","caller":"traceutil/trace.go:171","msg":"trace[1438981973] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"102.328209ms","start":"2026-09-16T15:52:26.691914Z","end":"2026-09-16T15:52:26.794242Z","steps":["trace[1438981973] 'read index received' (duration: 102.142827ms)","trace[1438981973] 'applied index is now lower than readState.Index' (duration: 184.682µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:52:26.794325Z","caller":"traceutil/trace.go:171","msg":"trace[1473954866] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"117.916541ms","start":"2026-09-16T15:52:26.676386Z","end":"2026-09-16T15:52:26.794303Z","steps":["trace[1473954866] 'process raft request' (duration: 117.734379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:52:26.794516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.593904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-16T15:52:26.794585Z","caller":"traceutil/trace.go:171","msg":"trace[1985240976] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1849; }","duration":"102.715346ms","start":"2026-09-16T15:52:26.691851Z","end":"2026-09-16T15:52:26.794567Z","steps":["trace[1985240976] 'agreement among raft nodes before linearized reading' (duration: 102.500373ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:52:27.050121Z","caller":"traceutil/trace.go:171","msg":"trace[1417497630] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"250.237251ms","start":"2026-09-16T15:52:26.799864Z","end":"2026-09-16T15:52:27.050101Z","steps":["trace[1417497630] 'process raft request' (duration: 244.58806ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:52:27.050534Z","caller":"traceutil/trace.go:171","msg":"trace[220478343] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"250.524866ms","start":"2026-09-16T15:52:26.799983Z","end":"2026-09-16T15:52:27.050507Z","steps":["trace[220478343] 'process raft request' (duration: 250.073888ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:52:27.050534Z","caller":"traceutil/trace.go:171","msg":"trace[1756023531] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1919; }","duration":"161.181185ms","start":"2026-09-16T15:52:26.889328Z","end":"2026-09-16T15:52:27.050509Z","steps":["trace[1756023531] 'read index received' (duration: 155.040726ms)","trace[1756023531] 'applied index is now lower than readState.Index' (duration: 6.139439ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:52:27.050685Z","caller":"traceutil/trace.go:171","msg":"trace[800739517] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"250.309282ms","start":"2026-09-16T15:52:26.80028Z","end":"2026-09-16T15:52:27.05059Z","steps":["trace[800739517] 'process raft request' (duration: 250.023717ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:52:27.051071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.672118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-16T15:52:27.051179Z","caller":"traceutil/trace.go:171","msg":"trace[534919567] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1854; }","duration":"144.80802ms","start":"2026-09-16T15:52:26.90634Z","end":"2026-09-16T15:52:27.051148Z","steps":["trace[534919567] 'agreement among raft nodes before linearized reading' (duration: 144.641238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:52:27.051152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.816864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-16T15:52:27.052382Z","caller":"traceutil/trace.go:171","msg":"trace[1817397257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1854; }","duration":"163.101876ms","start":"2026-09-16T15:52:26.889271Z","end":"2026-09-16T15:52:27.052373Z","steps":["trace[1817397257] 'agreement among raft nodes before linearized reading' (duration: 161.693783ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:52:27.051147Z","caller":"traceutil/trace.go:171","msg":"trace[1448209661] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"250.091428ms","start":"2026-09-16T15:52:26.801034Z","end":"2026-09-16T15:52:27.051125Z","steps":["trace[1448209661] 'process raft request' (duration: 249.371757ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:52:39.214544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.05753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T15:52:39.214739Z","caller":"traceutil/trace.go:171","msg":"trace[409344198] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2029; }","duration":"101.886272ms","start":"2026-09-16T15:52:39.112829Z","end":"2026-09-16T15:52:39.214715Z","steps":["trace[409344198] 'read index received' (duration: 30.25µs)","trace[409344198] 'applied index is now lower than readState.Index' (duration: 101.852792ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:52:39.214815Z","caller":"traceutil/trace.go:171","msg":"trace[648809831] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"263.080179ms","start":"2026-09-16T15:52:38.951682Z","end":"2026-09-16T15:52:39.214762Z","steps":["trace[648809831] 'process raft request' (duration: 42.682534ms)","trace[648809831] 'compare' (duration: 219.790107ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:52:39.215422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.577352ms","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-09-16T15:52:39.215474Z","caller":"traceutil/trace.go:171","msg":"trace[713466496] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1957; }","duration":"102.666274ms","start":"2026-09-16T15:52:39.112795Z","end":"2026-09-16T15:52:39.215461Z","steps":["trace[713466496] 'agreement among raft nodes before linearized reading' (duration: 102.035064ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:52:39.48844Z","caller":"traceutil/trace.go:171","msg":"trace[1885391177] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"267.661644ms","start":"2026-09-16T15:52:39.220741Z","end":"2026-09-16T15:52:39.488402Z","steps":["trace[1885391177] 'process raft request' (duration: 220.276412ms)","trace[1885391177] 'compare' (duration: 46.451498ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:52:39.488651Z","caller":"traceutil/trace.go:171","msg":"trace[190922211] linearizableReadLoop","detail":"{readStateIndex:2032; appliedIndex:2030; }","duration":"266.429026ms","start":"2026-09-16T15:52:39.221551Z","end":"2026-09-16T15:52:39.48798Z","steps":["trace[190922211] 'read index received' (duration: 38.245225ms)","trace[190922211] 'applied index is now lower than readState.Index' (duration: 228.181731ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:52:39.488623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.039285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-16T15:52:39.4888Z","caller":"traceutil/trace.go:171","msg":"trace[1935707979] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1959; }","duration":"267.244738ms","start":"2026-09-16T15:52:39.221524Z","end":"2026-09-16T15:52:39.488769Z","steps":["trace[1935707979] 'agreement among raft nodes before linearized reading' (duration: 266.986204ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:52:43.067832Z","caller":"traceutil/trace.go:171","msg":"trace[1416117675] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"339.564405ms","start":"2026-09-16T15:52:42.728235Z","end":"2026-09-16T15:52:43.0678Z","steps":["trace[1416117675] 'process raft request' (duration: 339.413673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:52:43.068015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:52:42.728218Z","time spent":"339.696688ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T15:52:43.068028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.671044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T15:52:43.068076Z","caller":"traceutil/trace.go:171","msg":"trace[286222558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1987; }","duration":"332.799157ms","start":"2026-09-16T15:52:42.735266Z","end":"2026-09-16T15:52:43.068065Z","steps":["trace[286222558] 'agreement among raft nodes before linearized reading' (duration: 332.627484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:52:43.06811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:52:42.735251Z","time spent":"332.851347ms","remote":"127.0.0.1:43468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-16T15:52:43.067822Z","caller":"traceutil/trace.go:171","msg":"trace[1882485430] linearizableReadLoop","detail":"{readStateIndex:2060; appliedIndex:2059; }","duration":"332.493161ms","start":"2026-09-16T15:52:42.735299Z","end":"2026-09-16T15:52:43.067793Z","steps":["trace[1882485430] 'read index received' (duration: 332.479181ms)","trace[1882485430] 'applied index is now lower than readState.Index' (duration: 11.71µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:52:43.069961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.907786ms","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-09-16T15:52:43.070054Z","caller":"traceutil/trace.go:171","msg":"trace[1691016090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1988; }","duration":"152.074518ms","start":"2026-09-16T15:52:42.917961Z","end":"2026-09-16T15:52:43.070035Z","steps":["trace[1691016090] 'agreement among raft nodes before linearized reading' (duration: 151.843675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:52:43.070345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.189626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-16T15:52:43.070386Z","caller":"traceutil/trace.go:171","msg":"trace[1799677033] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1988; }","duration":"149.252846ms","start":"2026-09-16T15:52:42.921118Z","end":"2026-09-16T15:52:43.070371Z","steps":["trace[1799677033] 'agreement among raft nodes before linearized reading' (duration: 149.126035ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:52:43.070412Z","caller":"traceutil/trace.go:171","msg":"trace[246721158] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"288.959826ms","start":"2026-09-16T15:52:42.781431Z","end":"2026-09-16T15:52:43.070391Z","steps":["trace[246721158] 'process raft request' (duration: 288.166954ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:53:06.519129Z","caller":"traceutil/trace.go:171","msg":"trace[294572377] linearizableReadLoop","detail":"{readStateIndex:2199; appliedIndex:2198; }","duration":"116.426017ms","start":"2026-09-16T15:53:06.40268Z","end":"2026-09-16T15:53:06.519106Z","steps":["trace[294572377] 'read index received' (duration: 116.233814ms)","trace[294572377] 'applied index is now lower than readState.Index' (duration: 191.443µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:53:06.519256Z","caller":"traceutil/trace.go:171","msg":"trace[1048717895] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"150.27222ms","start":"2026-09-16T15:53:06.368957Z","end":"2026-09-16T15:53:06.519229Z","steps":["trace[1048717895] 'process raft request' (duration: 149.978937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:53:06.519359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.670119ms","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-09-16T15:53:06.519397Z","caller":"traceutil/trace.go:171","msg":"trace[1044476186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2121; }","duration":"116.774143ms","start":"2026-09-16T15:53:06.402612Z","end":"2026-09-16T15:53:06.519386Z","steps":["trace[1044476186] 'agreement among raft nodes before linearized reading' (duration: 116.59002ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:54:10.107108Z","caller":"traceutil/trace.go:171","msg":"trace[938744437] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"125.676761ms","start":"2026-09-16T15:54:09.98141Z","end":"2026-09-16T15:54:10.107086Z","steps":["trace[938744437] 'process raft request' (duration: 121.961128ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:54:10.110396Z","caller":"traceutil/trace.go:171","msg":"trace[23586223] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"123.430715ms","start":"2026-09-16T15:54:09.986951Z","end":"2026-09-16T15:54:10.110382Z","steps":["trace[23586223] 'process raft request' (duration: 123.341134ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:54:44.288887Z","caller":"traceutil/trace.go:171","msg":"trace[939364501] linearizableReadLoop","detail":"{readStateIndex:2853; appliedIndex:2852; }","duration":"152.141951ms","start":"2026-09-16T15:54:44.136718Z","end":"2026-09-16T15:54:44.28886Z","steps":["trace[939364501] 'read index received' (duration: 147.858397ms)","trace[939364501] 'applied index is now lower than readState.Index' (duration: 4.282384ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:54:44.288993Z","caller":"traceutil/trace.go:171","msg":"trace[171642180] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"208.078923ms","start":"2026-09-16T15:54:44.080869Z","end":"2026-09-16T15:54:44.288948Z","steps":["trace[171642180] 'process raft request' (duration: 203.694544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:54:44.289113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.390113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-16T15:54:44.289147Z","caller":"traceutil/trace.go:171","msg":"trace[2141320867] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2753; }","duration":"152.478437ms","start":"2026-09-16T15:54:44.136658Z","end":"2026-09-16T15:54:44.289136Z","steps":["trace[2141320867] 'agreement among raft nodes before linearized reading' (duration: 152.32783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:54:44.619852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.770209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T15:54:44.620499Z","caller":"traceutil/trace.go:171","msg":"trace[840975126] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"264.206364ms","start":"2026-09-16T15:54:44.356275Z","end":"2026-09-16T15:54:44.620482Z","steps":["trace[840975126] 'process raft request' (duration: 101.735061ms)","trace[840975126] 'compare' (duration: 161.380151ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:54:44.890456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.522506ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-16T15:54:44.890614Z","caller":"traceutil/trace.go:171","msg":"trace[400094681] linearizableReadLoop","detail":"{readStateIndex:2857; appliedIndex:2855; }","duration":"336.685603ms","start":"2026-09-16T15:54:44.553911Z","end":"2026-09-16T15:54:44.890597Z","steps":["trace[400094681] 'read index received' (duration: 53.781799ms)","trace[400094681] 'applied index is now lower than readState.Index' (duration: 282.902134ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:54:44.890789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.871643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-16T15:54:44.890819Z","caller":"traceutil/trace.go:171","msg":"trace[498150277] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2756; }","duration":"336.956737ms","start":"2026-09-16T15:54:44.553852Z","end":"2026-09-16T15:54:44.890809Z","steps":["trace[498150277] 'agreement among raft nodes before linearized reading' (duration: 336.850782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:54:44.89085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:54:44.553829Z","time spent":"337.01167ms","remote":"127.0.0.1:43518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-09-16T15:54:44.891065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.791302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T15:54:44.891172Z","caller":"traceutil/trace.go:171","msg":"trace[1113704003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2756; }","duration":"152.965931ms","start":"2026-09-16T15:54:44.738173Z","end":"2026-09-16T15:54:44.891139Z","steps":["trace[1113704003] 'agreement among raft nodes before linearized reading' (duration: 152.753421ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:57:21.054516Z","caller":"traceutil/trace.go:171","msg":"trace[1483079585] transaction","detail":"{read_only:false; response_revision:3649; number_of_response:1; }","duration":"119.68466ms","start":"2026-09-16T15:57:20.934811Z","end":"2026-09-16T15:57:21.054496Z","steps":["trace[1483079585] 'process raft request' (duration: 119.462735ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:19.004863Z","caller":"traceutil/trace.go:171","msg":"trace[1381652340] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"178.24334ms","start":"2026-09-16T15:59:18.826556Z","end":"2026-09-16T15:59:19.004799Z","steps":["trace[1381652340] 'process raft request' (duration: 164.570988ms)","trace[1381652340] 'compare' (duration: 13.247034ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:59:19.184348Z","caller":"traceutil/trace.go:171","msg":"trace[880673862] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"172.732232ms","start":"2026-09-16T15:59:19.011592Z","end":"2026-09-16T15:59:19.184324Z","steps":["trace[880673862] 'process raft request' (duration: 142.710741ms)","trace[880673862] 'compare' (duration: 29.853498ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:59:19.184632Z","caller":"traceutil/trace.go:171","msg":"trace[1348445818] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"171.501789ms","start":"2026-09-16T15:59:19.013114Z","end":"2026-09-16T15:59:19.184616Z","steps":["trace[1348445818] 'process raft request' (duration: 171.173224ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:19.185538Z","caller":"traceutil/trace.go:171","msg":"trace[1252273090] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"171.743974ms","start":"2026-09-16T15:59:19.013773Z","end":"2026-09-16T15:59:19.185517Z","steps":["trace[1252273090] 'process raft request' (duration: 171.594071ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:19.185798Z","caller":"traceutil/trace.go:171","msg":"trace[378740417] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"100.715699ms","start":"2026-09-16T15:59:19.08507Z","end":"2026-09-16T15:59:19.185786Z","steps":["trace[378740417] 'process raft request' (duration: 100.662088ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:19.18586Z","caller":"traceutil/trace.go:171","msg":"trace[1271916318] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"171.586421ms","start":"2026-09-16T15:59:19.014251Z","end":"2026-09-16T15:59:19.185838Z","steps":["trace[1271916318] 'process raft request' (duration: 171.213004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:59:25.160915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.353628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T15:59:25.161053Z","caller":"traceutil/trace.go:171","msg":"trace[1253140746] linearizableReadLoop","detail":"{readStateIndex:5122; appliedIndex:5121; }","duration":"171.142604ms","start":"2026-09-16T15:59:24.989894Z","end":"2026-09-16T15:59:25.161037Z","steps":["trace[1253140746] 'read index received' (duration: 44.92µs)","trace[1253140746] 'applied index is now lower than readState.Index' (duration: 171.054833ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:59:25.161187Z","caller":"traceutil/trace.go:171","msg":"trace[2128527626] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"326.484853ms","start":"2026-09-16T15:59:24.834686Z","end":"2026-09-16T15:59:25.161171Z","steps":["trace[2128527626] 'process raft request' (duration: 104.791679ms)","trace[2128527626] 'compare' (duration: 221.236505ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:59:25.161205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.304697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T15:59:25.161232Z","caller":"traceutil/trace.go:171","msg":"trace[329987221] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4936; }","duration":"171.368718ms","start":"2026-09-16T15:59:24.989853Z","end":"2026-09-16T15:59:25.161222Z","steps":["trace[329987221] 'agreement among raft nodes before linearized reading' (duration: 171.228485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:59:25.161259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T15:59:24.834663Z","time spent":"326.559074ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T15:59:25.475089Z","caller":"traceutil/trace.go:171","msg":"trace[412893394] linearizableReadLoop","detail":"{readStateIndex:5132; appliedIndex:5129; }","duration":"101.317153ms","start":"2026-09-16T15:59:25.373749Z","end":"2026-09-16T15:59:25.475066Z","steps":["trace[412893394] 'read index received' (duration: 4.060704ms)","trace[412893394] 'applied index is now lower than readState.Index' (duration: 97.255669ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:59:25.475196Z","caller":"traceutil/trace.go:171","msg":"trace[1977489941] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"108.92427ms","start":"2026-09-16T15:59:25.366246Z","end":"2026-09-16T15:59:25.47517Z","steps":["trace[1977489941] 'process raft request' (duration: 91.236128ms)","trace[1977489941] 'compare' (duration: 17.107492ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:59:25.475302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.538006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-16T15:59:25.475289Z","caller":"traceutil/trace.go:171","msg":"trace[1136438708] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"108.832789ms","start":"2026-09-16T15:59:25.366384Z","end":"2026-09-16T15:59:25.475217Z","steps":["trace[1136438708] 'process raft request' (duration: 108.464443ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:25.475352Z","caller":"traceutil/trace.go:171","msg":"trace[1483861467] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4946; }","duration":"101.598337ms","start":"2026-09-16T15:59:25.373735Z","end":"2026-09-16T15:59:25.475333Z","steps":["trace[1483861467] 'agreement among raft nodes before linearized reading' (duration: 101.420544ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:25.475629Z","caller":"traceutil/trace.go:171","msg":"trace[282733712] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"106.903965ms","start":"2026-09-16T15:59:25.368691Z","end":"2026-09-16T15:59:25.475595Z","steps":["trace[282733712] 'process raft request' (duration: 106.273303ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:25.654517Z","caller":"traceutil/trace.go:171","msg":"trace[1199916255] linearizableReadLoop","detail":"{readStateIndex:5133; appliedIndex:5132; }","duration":"176.193023ms","start":"2026-09-16T15:59:25.478302Z","end":"2026-09-16T15:59:25.654496Z","steps":["trace[1199916255] 'read index received' (duration: 132.867876ms)","trace[1199916255] 'applied index is now lower than readState.Index' (duration: 43.324147ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T15:59:25.654643Z","caller":"traceutil/trace.go:171","msg":"trace[375038162] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"254.770142ms","start":"2026-09-16T15:59:25.399833Z","end":"2026-09-16T15:59:25.654603Z","steps":["trace[375038162] 'process raft request' (duration: 211.281422ms)","trace[375038162] 'compare' (duration: 43.265256ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:59:25.65472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.390467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ac04c1b4-0cf6-49ca-ae20-2c806d6cfffc\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-16T15:59:25.654757Z","caller":"traceutil/trace.go:171","msg":"trace[897657831] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ac04c1b4-0cf6-49ca-ae20-2c806d6cfffc; range_end:; response_count:1; response_revision:4947; }","duration":"176.4993ms","start":"2026-09-16T15:59:25.478245Z","end":"2026-09-16T15:59:25.654745Z","steps":["trace[897657831] 'agreement among raft nodes before linearized reading' (duration: 176.326336ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:25.675148Z","caller":"traceutil/trace.go:171","msg":"trace[924895048] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"107.791459ms","start":"2026-09-16T15:59:25.567332Z","end":"2026-09-16T15:59:25.675123Z","steps":["trace[924895048] 'process raft request' (duration: 107.560395ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:25.675456Z","caller":"traceutil/trace.go:171","msg":"trace[528766225] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"106.816663ms","start":"2026-09-16T15:59:25.568627Z","end":"2026-09-16T15:59:25.675443Z","steps":["trace[528766225] 'process raft request' (duration: 106.484106ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:25.675453Z","caller":"traceutil/trace.go:171","msg":"trace[1529284294] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"107.678518ms","start":"2026-09-16T15:59:25.567746Z","end":"2026-09-16T15:59:25.675425Z","steps":["trace[1529284294] 'process raft request' (duration: 107.302891ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:25.819192Z","caller":"traceutil/trace.go:171","msg":"trace[1973730500] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"104.960649ms","start":"2026-09-16T15:59:25.71418Z","end":"2026-09-16T15:59:25.819141Z","steps":["trace[1973730500] 'process raft request' (duration: 103.694876ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:25.819312Z","caller":"traceutil/trace.go:171","msg":"trace[1013045991] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"104.648393ms","start":"2026-09-16T15:59:25.714642Z","end":"2026-09-16T15:59:25.819291Z","steps":["trace[1013045991] 'process raft request' (duration: 104.438989ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:25.81959Z","caller":"traceutil/trace.go:171","msg":"trace[55871137] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"103.057904ms","start":"2026-09-16T15:59:25.716469Z","end":"2026-09-16T15:59:25.819527Z","steps":["trace[55871137] 'process raft request' (duration: 102.680697ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:39.991572Z","caller":"traceutil/trace.go:171","msg":"trace[1836695110] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"264.011291ms","start":"2026-09-16T15:59:39.727508Z","end":"2026-09-16T15:59:39.991519Z","steps":["trace[1836695110] 'process raft request' (duration: 263.770857ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:39.991646Z","caller":"traceutil/trace.go:171","msg":"trace[673401701] linearizableReadLoop","detail":"{readStateIndex:5375; appliedIndex:5375; }","duration":"255.768975ms","start":"2026-09-16T15:59:39.735841Z","end":"2026-09-16T15:59:39.99161Z","steps":["trace[673401701] 'read index received' (duration: 255.751684ms)","trace[673401701] 'applied index is now lower than readState.Index' (duration: 14.321µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T15:59:39.991833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.970499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T15:59:39.9919Z","caller":"traceutil/trace.go:171","msg":"trace[1023996232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5184; }","duration":"256.070021ms","start":"2026-09-16T15:59:39.735793Z","end":"2026-09-16T15:59:39.991863Z","steps":["trace[1023996232] 'agreement among raft nodes before linearized reading' (duration: 255.957769ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T15:59:39.994268Z","caller":"traceutil/trace.go:171","msg":"trace[2095156006] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"237.128443ms","start":"2026-09-16T15:59:39.757107Z","end":"2026-09-16T15:59:39.994236Z","steps":["trace[2095156006] 'process raft request' (duration: 236.97114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T15:59:39.994999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.575019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T15:59:39.995093Z","caller":"traceutil/trace.go:171","msg":"trace[151573742] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5185; }","duration":"101.769073ms","start":"2026-09-16T15:59:39.893308Z","end":"2026-09-16T15:59:39.995077Z","steps":["trace[151573742] 'agreement among raft nodes before linearized reading' (duration: 101.61146ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:00:06.041822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2869} {"level":"info","ts":"2026-09-16T16:00:06.149486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2869,"took":"106.48612ms","hash":1016207179,"current-db-size-bytes":52584448,"current-db-size":"53 MB","current-db-size-in-use-bytes":27652096,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-16T16:00:06.149537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1016207179,"revision":2869,"compact-revision":-1} {"level":"warn","ts":"2026-09-16T16:03:29.655886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.123365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:03:29.655983Z","caller":"traceutil/trace.go:171","msg":"trace[837493253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6831; }","duration":"113.25909ms","start":"2026-09-16T16:03:29.542704Z","end":"2026-09-16T16:03:29.655963Z","steps":["trace[837493253] 'range keys from in-memory index tree' (duration: 113.035301ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:03:30.181729Z","caller":"traceutil/trace.go:171","msg":"trace[2093377076] transaction","detail":"{read_only:false; response_revision:6832; number_of_response:1; }","duration":"124.081515ms","start":"2026-09-16T16:03:30.05759Z","end":"2026-09-16T16:03:30.181672Z","steps":["trace[2093377076] 'process raft request' (duration: 123.679998ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:03:30.406448Z","caller":"traceutil/trace.go:171","msg":"trace[2143719735] transaction","detail":"{read_only:false; response_revision:6833; number_of_response:1; }","duration":"136.856393ms","start":"2026-09-16T16:03:30.269559Z","end":"2026-09-16T16:03:30.406416Z","steps":["trace[2143719735] 'process raft request' (duration: 68.330153ms)","trace[2143719735] 'compare' (duration: 68.348323ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:03:30.847386Z","caller":"traceutil/trace.go:171","msg":"trace[49352016] linearizableReadLoop","detail":"{readStateIndex:7080; appliedIndex:7079; }","duration":"111.450002ms","start":"2026-09-16T16:03:30.735912Z","end":"2026-09-16T16:03:30.847362Z","steps":["trace[49352016] 'read index received' (duration: 111.291055ms)","trace[49352016] 'applied index is now lower than readState.Index' (duration: 157.987µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:03:30.847608Z","caller":"traceutil/trace.go:171","msg":"trace[1704684791] transaction","detail":"{read_only:false; response_revision:6834; number_of_response:1; }","duration":"353.249128ms","start":"2026-09-16T16:03:30.49434Z","end":"2026-09-16T16:03:30.847589Z","steps":["trace[1704684791] 'process raft request' (duration: 352.875301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:03:30.847778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T16:03:30.494321Z","time spent":"353.330391ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T16:03:30.847924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.602299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:03:30.848256Z","caller":"traceutil/trace.go:171","msg":"trace[615447436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6834; }","duration":"112.371671ms","start":"2026-09-16T16:03:30.735867Z","end":"2026-09-16T16:03:30.848239Z","steps":["trace[615447436] 'agreement among raft nodes before linearized reading' (duration: 111.605278ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:03:31.057186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.858711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T16:03:31.057413Z","caller":"traceutil/trace.go:171","msg":"trace[1495535945] transaction","detail":"{read_only:false; response_revision:6836; number_of_response:1; }","duration":"107.366132ms","start":"2026-09-16T16:03:30.950026Z","end":"2026-09-16T16:03:31.057392Z","steps":["trace[1495535945] 'process raft request' (duration: 107.278499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:03:31.05767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.733439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:03:31.057741Z","caller":"traceutil/trace.go:171","msg":"trace[1846991599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6836; }","duration":"207.841953ms","start":"2026-09-16T16:03:30.849882Z","end":"2026-09-16T16:03:31.057724Z","steps":["trace[1846991599] 'agreement among raft nodes before linearized reading' (duration: 207.715058ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:03:31.057738Z","caller":"traceutil/trace.go:171","msg":"trace[1226657248] transaction","detail":"{read_only:false; response_revision:6835; number_of_response:1; }","duration":"241.191914ms","start":"2026-09-16T16:03:30.816308Z","end":"2026-09-16T16:03:31.0575Z","steps":["trace[1226657248] 'process raft request' (duration: 126.869544ms)","trace[1226657248] 'compare' (duration: 113.435223ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:03:31.057975Z","caller":"traceutil/trace.go:171","msg":"trace[360198841] linearizableReadLoop","detail":"{readStateIndex:7081; appliedIndex:7080; }","duration":"207.670867ms","start":"2026-09-16T16:03:30.84992Z","end":"2026-09-16T16:03:31.057591Z","steps":["trace[360198841] 'read index received' (duration: 93.140037ms)","trace[360198841] 'applied index is now lower than readState.Index' (duration: 114.52768ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:03:31.069352Z","caller":"traceutil/trace.go:171","msg":"trace[1150564920] transaction","detail":"{read_only:false; response_revision:6837; number_of_response:1; }","duration":"118.349043ms","start":"2026-09-16T16:03:30.950981Z","end":"2026-09-16T16:03:31.06933Z","steps":["trace[1150564920] 'process raft request' (duration: 118.224838ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:06.049725Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5355} {"level":"warn","ts":"2026-09-16T16:05:06.221216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.961341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T16:05:06.221444Z","caller":"traceutil/trace.go:171","msg":"trace[1062801918] transaction","detail":"{read_only:false; response_revision:7398; number_of_response:1; }","duration":"104.146492ms","start":"2026-09-16T16:05:06.117257Z","end":"2026-09-16T16:05:06.221403Z","steps":["trace[1062801918] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; req_size:669; } (duration: 102.770235ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:06.239206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5355,"took":"187.910601ms","hash":2231931694,"current-db-size-bytes":52584448,"current-db-size":"53 MB","current-db-size-in-use-bytes":18628608,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-16T16:05:06.239271Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2231931694,"revision":5355,"compact-revision":2869} {"level":"info","ts":"2026-09-16T16:05:25.654151Z","caller":"traceutil/trace.go:171","msg":"trace[1323502853] linearizableReadLoop","detail":"{readStateIndex:7764; appliedIndex:7763; }","duration":"140.384651ms","start":"2026-09-16T16:05:25.513738Z","end":"2026-09-16T16:05:25.654123Z","steps":["trace[1323502853] 'read index received' (duration: 140.210616ms)","trace[1323502853] 'applied index is now lower than readState.Index' (duration: 169.875µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:25.654197Z","caller":"traceutil/trace.go:171","msg":"trace[1174331034] transaction","detail":"{read_only:false; response_revision:7492; number_of_response:1; }","duration":"146.799008ms","start":"2026-09-16T16:05:25.507376Z","end":"2026-09-16T16:05:25.654175Z","steps":["trace[1174331034] 'process raft request' (duration: 146.575611ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:25.654301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.538976ms","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-09-16T16:05:25.654354Z","caller":"traceutil/trace.go:171","msg":"trace[1625759915] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7492; }","duration":"140.63328ms","start":"2026-09-16T16:05:25.513706Z","end":"2026-09-16T16:05:25.654339Z","steps":["trace[1625759915] 'agreement among raft nodes before linearized reading' (duration: 140.535176ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:26.272785Z","caller":"traceutil/trace.go:171","msg":"trace[2031300883] transaction","detail":"{read_only:false; response_revision:7497; number_of_response:1; }","duration":"105.061ms","start":"2026-09-16T16:05:26.1677Z","end":"2026-09-16T16:05:26.272761Z","steps":["trace[2031300883] 'process raft request' (duration: 87.251698ms)","trace[2031300883] 'compare' (duration: 17.392377ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:27.816256Z","caller":"traceutil/trace.go:171","msg":"trace[127520108] transaction","detail":"{read_only:false; response_revision:7502; number_of_response:1; }","duration":"154.20993ms","start":"2026-09-16T16:05:27.662025Z","end":"2026-09-16T16:05:27.816235Z","steps":["trace[127520108] 'process raft request' (duration: 154.083135ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:27.817455Z","caller":"traceutil/trace.go:171","msg":"trace[1625613654] transaction","detail":"{read_only:false; response_revision:7504; number_of_response:1; }","duration":"135.725567ms","start":"2026-09-16T16:05:27.681711Z","end":"2026-09-16T16:05:27.817436Z","steps":["trace[1625613654] 'process raft request' (duration: 135.655485ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:27.817513Z","caller":"traceutil/trace.go:171","msg":"trace[514055237] transaction","detail":"{read_only:false; response_revision:7503; number_of_response:1; }","duration":"137.200327ms","start":"2026-09-16T16:05:27.680262Z","end":"2026-09-16T16:05:27.817462Z","steps":["trace[514055237] 'process raft request' (duration: 137.021491ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:29.960031Z","caller":"traceutil/trace.go:171","msg":"trace[168706522] transaction","detail":"{read_only:false; response_revision:7515; number_of_response:1; }","duration":"136.689732ms","start":"2026-09-16T16:05:29.823317Z","end":"2026-09-16T16:05:29.960007Z","steps":["trace[168706522] 'process raft request' (duration: 136.603559ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:29.96021Z","caller":"traceutil/trace.go:171","msg":"trace[1356555982] transaction","detail":"{read_only:false; response_revision:7514; number_of_response:1; }","duration":"137.004613ms","start":"2026-09-16T16:05:29.823196Z","end":"2026-09-16T16:05:29.9602Z","steps":["trace[1356555982] 'process raft request' (duration: 136.575858ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:29.960301Z","caller":"traceutil/trace.go:171","msg":"trace[631348938] linearizableReadLoop","detail":"{readStateIndex:7789; appliedIndex:7785; }","duration":"116.784204ms","start":"2026-09-16T16:05:29.843497Z","end":"2026-09-16T16:05:29.960281Z","steps":["trace[631348938] 'read index received' (duration: 60.859705ms)","trace[631348938] 'applied index is now lower than readState.Index' (duration: 55.923169ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T16:05:29.960396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.879337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T16:05:29.960427Z","caller":"traceutil/trace.go:171","msg":"trace[1710695763] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:7516; }","duration":"116.96895ms","start":"2026-09-16T16:05:29.843446Z","end":"2026-09-16T16:05:29.960415Z","steps":["trace[1710695763] 'agreement among raft nodes before linearized reading' (duration: 116.896348ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:29.960477Z","caller":"traceutil/trace.go:171","msg":"trace[38115624] transaction","detail":"{read_only:false; response_revision:7516; number_of_response:1; }","duration":"135.68886ms","start":"2026-09-16T16:05:29.824756Z","end":"2026-09-16T16:05:29.960445Z","steps":["trace[38115624] 'process raft request' (duration: 135.202413ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:40.397339Z","caller":"traceutil/trace.go:171","msg":"trace[675912026] transaction","detail":"{read_only:false; response_revision:7565; number_of_response:1; }","duration":"210.522745ms","start":"2026-09-16T16:05:40.18679Z","end":"2026-09-16T16:05:40.397313Z","steps":["trace[675912026] 'process raft request' (duration: 210.37274ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:40.413893Z","caller":"traceutil/trace.go:171","msg":"trace[970642542] linearizableReadLoop","detail":"{readStateIndex:7841; appliedIndex:7839; }","duration":"185.665955ms","start":"2026-09-16T16:05:40.228192Z","end":"2026-09-16T16:05:40.413858Z","steps":["trace[970642542] 'read index received' (duration: 169.072298ms)","trace[970642542] 'applied index is now lower than readState.Index' (duration: 16.592067ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:05:40.414119Z","caller":"traceutil/trace.go:171","msg":"trace[1623992372] transaction","detail":"{read_only:false; response_revision:7566; number_of_response:1; }","duration":"227.295407ms","start":"2026-09-16T16:05:40.186788Z","end":"2026-09-16T16:05:40.414083Z","steps":["trace[1623992372] 'process raft request' (duration: 226.855262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:05:40.414394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.183852ms","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-09-16T16:05:40.414487Z","caller":"traceutil/trace.go:171","msg":"trace[1092628854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7566; }","duration":"186.313016ms","start":"2026-09-16T16:05:40.228155Z","end":"2026-09-16T16:05:40.414468Z","steps":["trace[1092628854] 'agreement among raft nodes before linearized reading' (duration: 185.855912ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:05:40.521063Z","caller":"traceutil/trace.go:171","msg":"trace[774975105] transaction","detail":"{read_only:false; response_revision:7567; number_of_response:1; }","duration":"100.992386ms","start":"2026-09-16T16:05:40.419973Z","end":"2026-09-16T16:05:40.520965Z","steps":["trace[774975105] 'process raft request' (duration: 78.31706ms)","trace[774975105] 'compare' (duration: 22.109088ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:06:06.759625Z","caller":"traceutil/trace.go:171","msg":"trace[1625765969] transaction","detail":"{read_only:false; response_revision:7697; number_of_response:1; }","duration":"169.524216ms","start":"2026-09-16T16:06:06.590078Z","end":"2026-09-16T16:06:06.759602Z","steps":["trace[1625765969] 'process raft request' (duration: 169.377502ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T16:06:49.928493Z","caller":"traceutil/trace.go:171","msg":"trace[2122930957] linearizableReadLoop","detail":"{readStateIndex:8206; appliedIndex:8205; }","duration":"125.433485ms","start":"2026-09-16T16:06:49.803042Z","end":"2026-09-16T16:06:49.928476Z","steps":["trace[2122930957] 'read index received' (duration: 125.25155ms)","trace[2122930957] 'applied index is now lower than readState.Index' (duration: 181.345µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T16:06:49.928568Z","caller":"traceutil/trace.go:171","msg":"trace[1168956605] transaction","detail":"{read_only:false; response_revision:7917; number_of_response:1; }","duration":"137.384146ms","start":"2026-09-16T16:06:49.791157Z","end":"2026-09-16T16:06:49.928541Z","steps":["trace[1168956605] 'process raft request' (duration: 137.207051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T16:06:49.928742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.677073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-persistentvolumesusage\" ","response":"range_response_count:1 size:6971"} {"level":"info","ts":"2026-09-16T16:06:49.928826Z","caller":"traceutil/trace.go:171","msg":"trace[1827805642] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-persistentvolumesusage; range_end:; response_count:1; response_revision:7917; }","duration":"125.819207ms","start":"2026-09-16T16:06:49.802993Z","end":"2026-09-16T16:06:49.928812Z","steps":["trace[1827805642] 'agreement among raft nodes before linearized reading' (duration: 125.58466ms)"],"step_count":1}