{"level":"warn","ts":"2026-09-07T10:39:57.833851Z","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-07T10:39:57.833977Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.161: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.161:2380","--initial-cluster=instance=https://162.253.55.161:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.161:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.161: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-07T10:39:57.834044Z","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-07T10:39:57.834053Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.161:2380"]} {"level":"info","ts":"2026-09-07T10:39:57.834077Z","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-07T10:39:57.834764Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.161:2379"]} {"level":"info","ts":"2026-09-07T10:39:57.834845Z","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.161:2380"],"listen-peer-urls":["https://162.253.55.161:2380"],"advertise-client-urls":["https://162.253.55.161:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.161:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.161: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-07T10:39:57.839883Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.859712ms"} {"level":"info","ts":"2026-09-07T10:39:57.849839Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"383aa7db69884d67","cluster-id":"2ae15ce520a2ef1e"} {"level":"info","ts":"2026-09-07T10:39:57.849923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T10:39:57.849949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became follower at term 0"} {"level":"info","ts":"2026-09-07T10:39:57.849963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 383aa7db69884d67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T10:39:57.849974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became follower at term 1"} {"level":"info","ts":"2026-09-07T10:39:57.850012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 switched to configuration voters=(4051735375583399271)"} {"level":"warn","ts":"2026-09-07T10:39:57.856748Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T10:39:57.858085Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T10:39:57.859855Z","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-07T10:39:57.861777Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"383aa7db69884d67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T10:39:57.862185Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"383aa7db69884d67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T10:39:57.862385Z","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-07T10:39:57.862468Z","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-07T10:39:57.862521Z","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-07T10:39:57.864326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 switched to configuration voters=(4051735375583399271)"} {"level":"info","ts":"2026-09-07T10:39:57.872676Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ae15ce520a2ef1e","local-member-id":"383aa7db69884d67","added-peer-id":"383aa7db69884d67","added-peer-peer-urls":["https://162.253.55.161:2380"]} {"level":"info","ts":"2026-09-07T10:39:57.873899Z","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-07T10:39:57.874101Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.161:2380"} {"level":"info","ts":"2026-09-07T10:39:57.874157Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.161:2380"} {"level":"info","ts":"2026-09-07T10:39:57.874426Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"383aa7db69884d67","initial-advertise-peer-urls":["https://162.253.55.161:2380"],"listen-peer-urls":["https://162.253.55.161:2380"],"advertise-client-urls":["https://162.253.55.161:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.161:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T10:39:57.8745Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T10:39:58.650741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T10:39:58.650869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T10:39:58.650905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 received MsgPreVoteResp from 383aa7db69884d67 at term 1"} {"level":"info","ts":"2026-09-07T10:39:58.65093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became candidate at term 2"} {"level":"info","ts":"2026-09-07T10:39:58.650947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 received MsgVoteResp from 383aa7db69884d67 at term 2"} {"level":"info","ts":"2026-09-07T10:39:58.650966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became leader at term 2"} {"level":"info","ts":"2026-09-07T10:39:58.650985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 383aa7db69884d67 elected leader 383aa7db69884d67 at term 2"} {"level":"info","ts":"2026-09-07T10:39:58.652531Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"383aa7db69884d67","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.161:2379]}","request-path":"/0/members/383aa7db69884d67/attributes","cluster-id":"2ae15ce520a2ef1e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T10:39:58.652556Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:39:58.652785Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:39:58.653061Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:39:58.653313Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T10:39:58.653526Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T10:39:58.655296Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ae15ce520a2ef1e","local-member-id":"383aa7db69884d67","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:39:58.655421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T10:39:58.65557Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:39:58.655623Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:39:58.657614Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.161:2379"} {"level":"info","ts":"2026-09-07T10:40:00.42908Z","caller":"traceutil/trace.go:171","msg":"trace[1474476593] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"103.531271ms","start":"2026-09-07T10:40:00.325487Z","end":"2026-09-07T10:40:00.429018Z","steps":["trace[1474476593] 'process raft request' (duration: 94.233467ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:03.050334Z","caller":"traceutil/trace.go:171","msg":"trace[929528583] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:250; }","duration":"122.725074ms","start":"2026-09-07T10:40:02.927594Z","end":"2026-09-07T10:40:03.050319Z","steps":["trace[929528583] 'read index received' (duration: 122.652983ms)","trace[929528583] 'applied index is now lower than readState.Index' (duration: 71.051µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:40:03.050551Z","caller":"traceutil/trace.go:171","msg":"trace[565155979] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"316.605762ms","start":"2026-09-07T10:40:02.733933Z","end":"2026-09-07T10:40:03.050538Z","steps":["trace[565155979] 'process raft request' (duration: 316.290228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:03.050677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.955288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-09-07T10:40:03.050836Z","caller":"traceutil/trace.go:171","msg":"trace[851835280] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:245; }","duration":"123.256211ms","start":"2026-09-07T10:40:02.92755Z","end":"2026-09-07T10:40:03.050807Z","steps":["trace[851835280] 'agreement among raft nodes before linearized reading' (duration: 122.880486ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:03.051182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:02.73391Z","time spent":"316.664593ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T10:40:03.363488Z","caller":"traceutil/trace.go:171","msg":"trace[2064875538] linearizableReadLoop","detail":"{readStateIndex:252; appliedIndex:251; }","duration":"307.199058ms","start":"2026-09-07T10:40:03.05626Z","end":"2026-09-07T10:40:03.363459Z","steps":["trace[2064875538] 'read index received' (duration: 254.237944ms)","trace[2064875538] 'applied index is now lower than readState.Index' (duration: 52.959794ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:40:03.363553Z","caller":"traceutil/trace.go:171","msg":"trace[2098870257] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"309.101175ms","start":"2026-09-07T10:40:03.054412Z","end":"2026-09-07T10:40:03.363513Z","steps":["trace[2098870257] 'process raft request' (duration: 256.063129ms)","trace[2098870257] 'compare' (duration: 52.802473ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:40:03.363739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:03.054316Z","time spent":"309.350838ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T10:40:03.363921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.634314ms","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-09-07T10:40:03.363993Z","caller":"traceutil/trace.go:171","msg":"trace[1612282661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:246; }","duration":"307.745926ms","start":"2026-09-07T10:40:03.056208Z","end":"2026-09-07T10:40:03.363954Z","steps":["trace[1612282661] 'agreement among raft nodes before linearized reading' (duration: 307.351221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:03.364045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:03.056183Z","time spent":"307.849837ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-07T10:40:03.671796Z","caller":"traceutil/trace.go:171","msg":"trace[1391281091] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"303.953504ms","start":"2026-09-07T10:40:03.36782Z","end":"2026-09-07T10:40:03.671774Z","steps":["trace[1391281091] 'read index received' (duration: 302.049547ms)","trace[1391281091] 'applied index is now lower than readState.Index' (duration: 1.902797ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:40:03.671919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.070916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:40:03.671946Z","caller":"traceutil/trace.go:171","msg":"trace[1939598520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:247; }","duration":"304.157347ms","start":"2026-09-07T10:40:03.367781Z","end":"2026-09-07T10:40:03.671939Z","steps":["trace[1939598520] 'agreement among raft nodes before linearized reading' (duration: 304.082956ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:03.671881Z","caller":"traceutil/trace.go:171","msg":"trace[27206876] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"612.757965ms","start":"2026-09-07T10:40:03.059088Z","end":"2026-09-07T10:40:03.671846Z","steps":["trace[27206876] 'process raft request' (duration: 610.541274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:03.671973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:03.367756Z","time spent":"304.211747ms","remote":"127.0.0.1:56568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T10:40:03.672491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:03.059066Z","time spent":"612.933458ms","remote":"127.0.0.1:56730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:40:03.681089Z","caller":"traceutil/trace.go:171","msg":"trace[878041881] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"311.955546ms","start":"2026-09-07T10:40:03.369095Z","end":"2026-09-07T10:40:03.68105Z","steps":["trace[878041881] 'process raft request' (duration: 311.701323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:03.681253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:03.369074Z","time spent":"312.091149ms","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T10:40:03.681293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.402037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-07T10:40:03.68134Z","caller":"traceutil/trace.go:171","msg":"trace[2099970595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:250; }","duration":"313.467838ms","start":"2026-09-07T10:40:03.367858Z","end":"2026-09-07T10:40:03.681326Z","steps":["trace[2099970595] 'agreement among raft nodes before linearized reading' (duration: 313.334606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:03.68142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:03.367758Z","time spent":"313.65295ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-07T10:40:03.681262Z","caller":"traceutil/trace.go:171","msg":"trace[481332872] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"311.422099ms","start":"2026-09-07T10:40:03.36977Z","end":"2026-09-07T10:40:03.681192Z","steps":["trace[481332872] 'process raft request' (duration: 311.140465ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:03.68182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:03.369757Z","time spent":"311.837135ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:40:08.426084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.55684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-09-07T10:40:08.426341Z","caller":"traceutil/trace.go:171","msg":"trace[235067640] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:347; }","duration":"189.846113ms","start":"2026-09-07T10:40:08.236446Z","end":"2026-09-07T10:40:08.426292Z","steps":["trace[235067640] 'range keys from in-memory index tree' (duration: 189.374827ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:08.426108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.37054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-09-07T10:40:08.426431Z","caller":"traceutil/trace.go:171","msg":"trace[1031835066] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:347; }","duration":"139.745334ms","start":"2026-09-07T10:40:08.286663Z","end":"2026-09-07T10:40:08.426408Z","steps":["trace[1031835066] 'range keys from in-memory index tree' (duration: 139.254117ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:08.569821Z","caller":"traceutil/trace.go:171","msg":"trace[521392352] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"126.264512ms","start":"2026-09-07T10:40:08.443527Z","end":"2026-09-07T10:40:08.569791Z","steps":["trace[521392352] 'process raft request' (duration: 125.37236ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:36.469079Z","caller":"traceutil/trace.go:171","msg":"trace[450411689] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"113.139828ms","start":"2026-09-07T10:40:36.355907Z","end":"2026-09-07T10:40:36.469047Z","steps":["trace[450411689] 'process raft request' (duration: 112.969889ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:39.263474Z","caller":"traceutil/trace.go:171","msg":"trace[350610793] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"117.574571ms","start":"2026-09-07T10:40:39.145869Z","end":"2026-09-07T10:40:39.263444Z","steps":["trace[350610793] 'process raft request' (duration: 117.389722ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:39.27378Z","caller":"traceutil/trace.go:171","msg":"trace[2025793945] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"103.904432ms","start":"2026-09-07T10:40:39.169837Z","end":"2026-09-07T10:40:39.273742Z","steps":["trace[2025793945] 'process raft request' (duration: 103.549015ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:39.273891Z","caller":"traceutil/trace.go:171","msg":"trace[107613901] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"122.278231ms","start":"2026-09-07T10:40:39.151596Z","end":"2026-09-07T10:40:39.273874Z","steps":["trace[107613901] 'process raft request' (duration: 121.60503ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:39.273994Z","caller":"traceutil/trace.go:171","msg":"trace[2036965995] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"104.107961ms","start":"2026-09-07T10:40:39.169873Z","end":"2026-09-07T10:40:39.273981Z","steps":["trace[2036965995] 'process raft request' (duration: 103.547845ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:39.27405Z","caller":"traceutil/trace.go:171","msg":"trace[470267971] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"123.191464ms","start":"2026-09-07T10:40:39.150849Z","end":"2026-09-07T10:40:39.274041Z","steps":["trace[470267971] 'process raft request' (duration: 122.23946ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:39.274075Z","caller":"traceutil/trace.go:171","msg":"trace[677939127] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"108.373841ms","start":"2026-09-07T10:40:39.165692Z","end":"2026-09-07T10:40:39.274066Z","steps":["trace[677939127] 'process raft request' (duration: 107.609655ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:39.274127Z","caller":"traceutil/trace.go:171","msg":"trace[450623420] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"108.313808ms","start":"2026-09-07T10:40:39.165802Z","end":"2026-09-07T10:40:39.274116Z","steps":["trace[450623420] 'process raft request' (duration: 107.535211ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:39.274154Z","caller":"traceutil/trace.go:171","msg":"trace[1194266648] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"102.424743ms","start":"2026-09-07T10:40:39.171713Z","end":"2026-09-07T10:40:39.274138Z","steps":["trace[1194266648] 'process raft request' (duration: 101.743031ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:43.887829Z","caller":"traceutil/trace.go:171","msg":"trace[956769688] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"104.311599ms","start":"2026-09-07T10:40:43.783492Z","end":"2026-09-07T10:40:43.887804Z","steps":["trace[956769688] 'process raft request' (duration: 102.647244ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:46.10547Z","caller":"traceutil/trace.go:171","msg":"trace[2047512311] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"201.5674ms","start":"2026-09-07T10:40:45.903863Z","end":"2026-09-07T10:40:46.10543Z","steps":["trace[2047512311] 'process raft request' (duration: 164.500268ms)","trace[2047512311] 'compare' (duration: 36.577491ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:40:46.362695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.081936ms","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-07T10:40:46.362815Z","caller":"traceutil/trace.go:171","msg":"trace[123486150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"104.257293ms","start":"2026-09-07T10:40:46.258531Z","end":"2026-09-07T10:40:46.362788Z","steps":["trace[123486150] 'range keys from in-memory index tree' (duration: 103.888017ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:46.510583Z","caller":"traceutil/trace.go:171","msg":"trace[740708124] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"211.529889ms","start":"2026-09-07T10:40:46.299007Z","end":"2026-09-07T10:40:46.510537Z","steps":["trace[740708124] 'process raft request' (duration: 210.035514ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:46.522852Z","caller":"traceutil/trace.go:171","msg":"trace[1217544933] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"155.097855ms","start":"2026-09-07T10:40:46.367714Z","end":"2026-09-07T10:40:46.522812Z","steps":["trace[1217544933] 'process raft request' (duration: 154.178865ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:47.071449Z","caller":"traceutil/trace.go:171","msg":"trace[559797197] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"497.207161ms","start":"2026-09-07T10:40:46.574207Z","end":"2026-09-07T10:40:47.071414Z","steps":["trace[559797197] 'process raft request' (duration: 496.989182ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:47.071792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:46.574188Z","time spent":"497.355568ms","remote":"127.0.0.1:56784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T10:41:03.177046Z","caller":"traceutil/trace.go:171","msg":"trace[1551206788] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"100.631225ms","start":"2026-09-07T10:41:03.076391Z","end":"2026-09-07T10:41:03.177022Z","steps":["trace[1551206788] 'read index received' (duration: 100.518081ms)","trace[1551206788] 'applied index is now lower than readState.Index' (duration: 112.014µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:41:03.177247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.836003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:41:03.177284Z","caller":"traceutil/trace.go:171","msg":"trace[1229866883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"100.912135ms","start":"2026-09-07T10:41:03.076361Z","end":"2026-09-07T10:41:03.177274Z","steps":["trace[1229866883] 'agreement among raft nodes before linearized reading' (duration: 100.76327ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:41:03.17757Z","caller":"traceutil/trace.go:171","msg":"trace[50797986] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"162.301216ms","start":"2026-09-07T10:41:03.015236Z","end":"2026-09-07T10:41:03.177537Z","steps":["trace[50797986] 'process raft request' (duration: 161.665073ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:41:20.441004Z","caller":"traceutil/trace.go:171","msg":"trace[1789387733] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"115.98062ms","start":"2026-09-07T10:41:20.324972Z","end":"2026-09-07T10:41:20.440952Z","steps":["trace[1789387733] 'process raft request' (duration: 115.743802ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:41:34.652488Z","caller":"traceutil/trace.go:171","msg":"trace[2091576419] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"123.319967ms","start":"2026-09-07T10:41:34.529132Z","end":"2026-09-07T10:41:34.652452Z","steps":["trace[2091576419] 'read index received' (duration: 122.987818ms)","trace[2091576419] 'applied index is now lower than readState.Index' (duration: 330.769µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:41:34.652644Z","caller":"traceutil/trace.go:171","msg":"trace[1245541589] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"126.084835ms","start":"2026-09-07T10:41:34.526532Z","end":"2026-09-07T10:41:34.652617Z","steps":["trace[1245541589] 'process raft request' (duration: 125.702414ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:41:34.652831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.675907ms","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-09-07T10:41:34.652879Z","caller":"traceutil/trace.go:171","msg":"trace[2040634953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"123.78835ms","start":"2026-09-07T10:41:34.529079Z","end":"2026-09-07T10:41:34.652867Z","steps":["trace[2040634953] 'agreement among raft nodes before linearized reading' (duration: 123.523062ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:41:34.851045Z","caller":"traceutil/trace.go:171","msg":"trace[1045382540] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"192.610107ms","start":"2026-09-07T10:41:34.658378Z","end":"2026-09-07T10:41:34.850989Z","steps":["trace[1045382540] 'process raft request' (duration: 156.566452ms)","trace[1045382540] 'compare' (duration: 35.686895ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:41:55.103162Z","caller":"traceutil/trace.go:171","msg":"trace[1627542902] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"125.947313ms","start":"2026-09-07T10:41:54.97718Z","end":"2026-09-07T10:41:55.103127Z","steps":["trace[1627542902] 'process raft request' (duration: 28.840736ms)","trace[1627542902] 'compare' (duration: 96.698267ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:41:57.582869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.964935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T10:41:57.583049Z","caller":"traceutil/trace.go:171","msg":"trace[1949821876] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1477; }","duration":"129.214971ms","start":"2026-09-07T10:41:57.453797Z","end":"2026-09-07T10:41:57.583012Z","steps":["trace[1949821876] 'count revisions from in-memory index tree' (duration: 128.896453ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:22.089182Z","caller":"traceutil/trace.go:171","msg":"trace[581838350] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"248.333082ms","start":"2026-09-07T10:42:21.840819Z","end":"2026-09-07T10:42:22.089152Z","steps":["trace[581838350] 'process raft request' (duration: 248.114568ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:35.626539Z","caller":"traceutil/trace.go:171","msg":"trace[769807913] linearizableReadLoop","detail":"{readStateIndex:2074; appliedIndex:2073; }","duration":"172.212771ms","start":"2026-09-07T10:42:35.45429Z","end":"2026-09-07T10:42:35.626502Z","steps":["trace[769807913] 'read index received' (duration: 170.405244ms)","trace[769807913] 'applied index is now lower than readState.Index' (duration: 1.803367ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:42:35.626667Z","caller":"traceutil/trace.go:171","msg":"trace[2079158119] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"188.999709ms","start":"2026-09-07T10:42:35.437622Z","end":"2026-09-07T10:42:35.626622Z","steps":["trace[2079158119] 'process raft request' (duration: 187.092901ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:35.626827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.517247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-09-07T10:42:35.626823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.690047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:42:35.626888Z","caller":"traceutil/trace.go:171","msg":"trace[1501884000] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1997; }","duration":"172.614569ms","start":"2026-09-07T10:42:35.454239Z","end":"2026-09-07T10:42:35.626853Z","steps":["trace[1501884000] 'agreement among raft nodes before linearized reading' (duration: 172.460656ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:35.626886Z","caller":"traceutil/trace.go:171","msg":"trace[2100664273] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1997; }","duration":"156.778428ms","start":"2026-09-07T10:42:35.470088Z","end":"2026-09-07T10:42:35.626867Z","steps":["trace[2100664273] 'agreement among raft nodes before linearized reading' (duration: 156.668676ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:57.803692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.92123ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-07T10:42:57.803824Z","caller":"traceutil/trace.go:171","msg":"trace[1860872621] linearizableReadLoop","detail":"{readStateIndex:2209; appliedIndex:2208; }","duration":"613.053854ms","start":"2026-09-07T10:42:57.190744Z","end":"2026-09-07T10:42:57.803798Z","steps":["trace[1860872621] 'read index received' (duration: 104.812µs)","trace[1860872621] 'applied index is now lower than readState.Index' (duration: 612.947122ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:42:57.804148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.396011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-07T10:42:57.804191Z","caller":"traceutil/trace.go:171","msg":"trace[518806023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2127; }","duration":"613.518303ms","start":"2026-09-07T10:42:57.190662Z","end":"2026-09-07T10:42:57.80418Z","steps":["trace[518806023] 'agreement among raft nodes before linearized reading' (duration: 613.31932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:57.804222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:57.190614Z","time spent":"613.600075ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-07T10:42:57.824211Z","caller":"traceutil/trace.go:171","msg":"trace[611321480] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"334.614003ms","start":"2026-09-07T10:42:57.489559Z","end":"2026-09-07T10:42:57.824173Z","steps":["trace[611321480] 'process raft request' (duration: 334.367258ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:57.824466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:57.489525Z","time spent":"334.794517ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:42:58.185991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.670285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:42:58.186502Z","caller":"traceutil/trace.go:171","msg":"trace[2057040316] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"418.755378ms","start":"2026-09-07T10:42:57.76772Z","end":"2026-09-07T10:42:58.186476Z","steps":["trace[2057040316] 'process raft request' (duration: 216.504782ms)","trace[2057040316] 'compare' (duration: 201.526022ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:42:58.186607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:57.767701Z","time spent":"418.854269ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:42:58.188976Z","caller":"traceutil/trace.go:171","msg":"trace[1195918701] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2209; }","duration":"385.084738ms","start":"2026-09-07T10:42:57.803861Z","end":"2026-09-07T10:42:58.188946Z","steps":["trace[1195918701] 'read index received' (duration: 20.066316ms)","trace[1195918701] 'applied index is now lower than readState.Index' (duration: 365.016102ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:42:58.189109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.454991ms","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-07T10:42:58.189186Z","caller":"traceutil/trace.go:171","msg":"trace[1527512047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2130; }","duration":"427.572013ms","start":"2026-09-07T10:42:57.761596Z","end":"2026-09-07T10:42:58.189168Z","steps":["trace[1527512047] 'agreement among raft nodes before linearized reading' (duration: 427.364179ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:58.189082Z","caller":"traceutil/trace.go:171","msg":"trace[2070316824] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"380.210697ms","start":"2026-09-07T10:42:57.80884Z","end":"2026-09-07T10:42:58.189051Z","steps":["trace[2070316824] 'process raft request' (duration: 377.241941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:58.189239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:57.761571Z","time spent":"427.655914ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-07T10:42:58.189508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:57.808815Z","time spent":"380.455211ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:42:58.189558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.794997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-07T10:42:58.189697Z","caller":"traceutil/trace.go:171","msg":"trace[88552056] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2130; }","duration":"427.96631ms","start":"2026-09-07T10:42:57.761709Z","end":"2026-09-07T10:42:58.189676Z","steps":["trace[88552056] 'agreement among raft nodes before linearized reading' (duration: 427.744736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:58.189754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.166312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-09-07T10:42:58.189781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:57.761679Z","time spent":"428.086812ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-07T10:42:58.189612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.095024ms","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-09-07T10:42:58.1898Z","caller":"traceutil/trace.go:171","msg":"trace[739404134] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2130; }","duration":"297.240563ms","start":"2026-09-07T10:42:57.892545Z","end":"2026-09-07T10:42:58.189785Z","steps":["trace[739404134] 'agreement among raft nodes before linearized reading' (duration: 297.12122ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:58.189928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.439048ms","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-09-07T10:42:58.18997Z","caller":"traceutil/trace.go:171","msg":"trace[266542237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2130; }","duration":"770.483811ms","start":"2026-09-07T10:42:57.419472Z","end":"2026-09-07T10:42:58.189956Z","steps":["trace[266542237] 'agreement among raft nodes before linearized reading' (duration: 770.039932ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:58.190079Z","caller":"traceutil/trace.go:171","msg":"trace[278368413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2130; }","duration":"810.686383ms","start":"2026-09-07T10:42:57.379367Z","end":"2026-09-07T10:42:58.190053Z","steps":["trace[278368413] 'agreement among raft nodes before linearized reading' (duration: 810.133083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:58.190123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:57.419448Z","time spent":"770.658924ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-07T10:42:58.190165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:57.379332Z","time spent":"810.823335ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-07T10:42:58.189855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.549401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:42:58.190243Z","caller":"traceutil/trace.go:171","msg":"trace[943873594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2130; }","duration":"113.020309ms","start":"2026-09-07T10:42:58.077209Z","end":"2026-09-07T10:42:58.190229Z","steps":["trace[943873594] 'agreement among raft nodes before linearized reading' (duration: 112.616981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:58.190466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.943282ms","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-07T10:42:58.190527Z","caller":"traceutil/trace.go:171","msg":"trace[1916681498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2130; }","duration":"699.050534ms","start":"2026-09-07T10:42:57.491459Z","end":"2026-09-07T10:42:58.190509Z","steps":["trace[1916681498] 'agreement among raft nodes before linearized reading' (duration: 697.622087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:58.190624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:57.491429Z","time spent":"699.127975ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-07T10:44:40.695352Z","caller":"traceutil/trace.go:171","msg":"trace[1050234630] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"120.215713ms","start":"2026-09-07T10:44:40.574746Z","end":"2026-09-07T10:44:40.694962Z","steps":["trace[1050234630] 'process raft request' (duration: 119.973529ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:44:40.88529Z","caller":"traceutil/trace.go:171","msg":"trace[59039482] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"148.381188ms","start":"2026-09-07T10:44:40.736855Z","end":"2026-09-07T10:44:40.885237Z","steps":["trace[59039482] 'process raft request' (duration: 113.868291ms)","trace[59039482] 'compare' (duration: 34.173001ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:44:40.886394Z","caller":"traceutil/trace.go:171","msg":"trace[1747927771] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"148.620491ms","start":"2026-09-07T10:44:40.737744Z","end":"2026-09-07T10:44:40.886365Z","steps":["trace[1747927771] 'process raft request' (duration: 148.273896ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:14.213703Z","caller":"traceutil/trace.go:171","msg":"trace[1006317066] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"102.072383ms","start":"2026-09-07T10:49:14.1116Z","end":"2026-09-07T10:49:14.213673Z","steps":["trace[1006317066] 'process raft request' (duration: 101.90488ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:19.960569Z","caller":"traceutil/trace.go:171","msg":"trace[1338319060] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"116.668525ms","start":"2026-09-07T10:49:19.843882Z","end":"2026-09-07T10:49:19.96055Z","steps":["trace[1338319060] 'process raft request' (duration: 116.571753ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:20.766213Z","caller":"traceutil/trace.go:171","msg":"trace[338530236] linearizableReadLoop","detail":"{readStateIndex:5105; appliedIndex:5104; }","duration":"253.833324ms","start":"2026-09-07T10:49:20.512347Z","end":"2026-09-07T10:49:20.76618Z","steps":["trace[338530236] 'read index received' (duration: 253.581279ms)","trace[338530236] 'applied index is now lower than readState.Index' (duration: 250.975µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:20.766348Z","caller":"traceutil/trace.go:171","msg":"trace[88606417] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"265.346044ms","start":"2026-09-07T10:49:20.500985Z","end":"2026-09-07T10:49:20.766332Z","steps":["trace[88606417] 'process raft request' (duration: 265.034178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:20.766647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.943672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T10:49:20.766718Z","caller":"traceutil/trace.go:171","msg":"trace[522278164] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:4916; }","duration":"178.102545ms","start":"2026-09-07T10:49:20.588592Z","end":"2026-09-07T10:49:20.766694Z","steps":["trace[522278164] 'agreement among raft nodes before linearized reading' (duration: 177.86334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:20.766912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.89997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-07T10:49:20.766993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.120519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T10:49:20.76706Z","caller":"traceutil/trace.go:171","msg":"trace[631858818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4916; }","duration":"130.230686ms","start":"2026-09-07T10:49:20.63681Z","end":"2026-09-07T10:49:20.76704Z","steps":["trace[631858818] 'agreement among raft nodes before linearized reading' (duration: 129.775356ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:20.767067Z","caller":"traceutil/trace.go:171","msg":"trace[1191321520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4916; }","duration":"158.260691ms","start":"2026-09-07T10:49:20.608789Z","end":"2026-09-07T10:49:20.76705Z","steps":["trace[1191321520] 'agreement among raft nodes before linearized reading' (duration: 158.056267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:20.767062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.702512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-07T10:49:20.767114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.78769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b35b501d-64c0-4da0-a5fd-45ab84328181\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-09-07T10:49:20.767139Z","caller":"traceutil/trace.go:171","msg":"trace[1513062030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4916; }","duration":"254.796635ms","start":"2026-09-07T10:49:20.512327Z","end":"2026-09-07T10:49:20.767123Z","steps":["trace[1513062030] 'agreement among raft nodes before linearized reading' (duration: 254.548319ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:20.767162Z","caller":"traceutil/trace.go:171","msg":"trace[199221920] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b35b501d-64c0-4da0-a5fd-45ab84328181; range_end:; response_count:1; response_revision:4916; }","duration":"110.850762ms","start":"2026-09-07T10:49:20.656294Z","end":"2026-09-07T10:49:20.767145Z","steps":["trace[199221920] 'agreement among raft nodes before linearized reading' (duration: 110.74728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:20.766919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.992412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T10:49:20.767233Z","caller":"traceutil/trace.go:171","msg":"trace[1393620434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4916; }","duration":"154.473632ms","start":"2026-09-07T10:49:20.612744Z","end":"2026-09-07T10:49:20.767218Z","steps":["trace[1393620434] 'agreement among raft nodes before linearized reading' (duration: 153.866049ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:20.896511Z","caller":"traceutil/trace.go:171","msg":"trace[2083434370] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"123.569158ms","start":"2026-09-07T10:49:20.77291Z","end":"2026-09-07T10:49:20.896479Z","steps":["trace[2083434370] 'process raft request' (duration: 123.300402ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:20.896559Z","caller":"traceutil/trace.go:171","msg":"trace[1334462144] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"120.068384ms","start":"2026-09-07T10:49:20.776466Z","end":"2026-09-07T10:49:20.896535Z","steps":["trace[1334462144] 'process raft request' (duration: 119.960112ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:20.896563Z","caller":"traceutil/trace.go:171","msg":"trace[1879913632] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"123.373723ms","start":"2026-09-07T10:49:20.773163Z","end":"2026-09-07T10:49:20.896537Z","steps":["trace[1879913632] 'process raft request' (duration: 123.201099ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:20.896616Z","caller":"traceutil/trace.go:171","msg":"trace[280074569] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"123.560197ms","start":"2026-09-07T10:49:20.773033Z","end":"2026-09-07T10:49:20.896593Z","steps":["trace[280074569] 'process raft request' (duration: 123.292011ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:20.896718Z","caller":"traceutil/trace.go:171","msg":"trace[942413073] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"123.521486ms","start":"2026-09-07T10:49:20.773182Z","end":"2026-09-07T10:49:20.896703Z","steps":["trace[942413073] 'process raft request' (duration: 123.21136ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:22.864438Z","caller":"traceutil/trace.go:171","msg":"trace[2082234697] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"174.736148ms","start":"2026-09-07T10:49:22.68967Z","end":"2026-09-07T10:49:22.864406Z","steps":["trace[2082234697] 'process raft request' (duration: 136.998262ms)","trace[2082234697] 'compare' (duration: 37.558682ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:49:29.153324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.313924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:49:29.153542Z","caller":"traceutil/trace.go:171","msg":"trace[2048544599] linearizableReadLoop","detail":"{readStateIndex:5288; appliedIndex:5286; }","duration":"390.986355ms","start":"2026-09-07T10:49:28.762539Z","end":"2026-09-07T10:49:29.153526Z","steps":["trace[2048544599] 'read index received' (duration: 172.454908ms)","trace[2048544599] 'applied index is now lower than readState.Index' (duration: 218.530397ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:29.15362Z","caller":"traceutil/trace.go:171","msg":"trace[1871820574] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"400.426771ms","start":"2026-09-07T10:49:28.753181Z","end":"2026-09-07T10:49:29.153608Z","steps":["trace[1871820574] 'process raft request' (duration: 181.75302ms)","trace[1871820574] 'compare' (duration: 218.087419ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:29.153655Z","caller":"traceutil/trace.go:171","msg":"trace[204187889] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"363.283123ms","start":"2026-09-07T10:49:28.790342Z","end":"2026-09-07T10:49:29.153625Z","steps":["trace[204187889] 'process raft request' (duration: 363.14881ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.153694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:28.753143Z","time spent":"400.517593ms","remote":"127.0.0.1:56746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:49:29.153735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:28.790323Z","time spent":"363.376255ms","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":924,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T10:49:29.153978Z","caller":"traceutil/trace.go:171","msg":"trace[1996268148] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"394.530059ms","start":"2026-09-07T10:49:28.759437Z","end":"2026-09-07T10:49:29.153967Z","steps":["trace[1996268148] 'process raft request' (duration: 393.994018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.153975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.431315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-09-07T10:49:29.15402Z","caller":"traceutil/trace.go:171","msg":"trace[1992574854] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5093; }","duration":"391.476526ms","start":"2026-09-07T10:49:28.762534Z","end":"2026-09-07T10:49:29.154011Z","steps":["trace[1992574854] 'agreement among raft nodes before linearized reading' (duration: 391.303312ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.154034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:28.759427Z","time spent":"394.57882ms","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T10:49:29.154049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:28.762492Z","time spent":"391.547957ms","remote":"127.0.0.1:39046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-09-07T10:49:29.154246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.791781ms","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-07T10:49:29.154274Z","caller":"traceutil/trace.go:171","msg":"trace[1070294503] 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:5093; }","duration":"375.841643ms","start":"2026-09-07T10:49:28.778422Z","end":"2026-09-07T10:49:29.154264Z","steps":["trace[1070294503] 'agreement among raft nodes before linearized reading' (duration: 375.762401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.154297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:28.778408Z","time spent":"375.882763ms","remote":"127.0.0.1:56718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-09-07T10:49:29.154401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.399059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-07T10:49:29.154431Z","caller":"traceutil/trace.go:171","msg":"trace[1380772409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5093; }","duration":"209.44104ms","start":"2026-09-07T10:49:28.944981Z","end":"2026-09-07T10:49:29.154422Z","steps":["trace[1380772409] 'agreement among raft nodes before linearized reading' (duration: 209.347088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.15447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.988851ms","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-09-07T10:49:29.154497Z","caller":"traceutil/trace.go:171","msg":"trace[173205952] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5093; }","duration":"210.045522ms","start":"2026-09-07T10:49:28.944441Z","end":"2026-09-07T10:49:29.154487Z","steps":["trace[173205952] 'agreement among raft nodes before linearized reading' (duration: 209.971921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.154594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.881048ms","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-07T10:49:29.154615Z","caller":"traceutil/trace.go:171","msg":"trace[1040204179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5093; }","duration":"221.913158ms","start":"2026-09-07T10:49:28.932695Z","end":"2026-09-07T10:49:29.154608Z","steps":["trace[1040204179] 'agreement among raft nodes before linearized reading' (duration: 221.853437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.15477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.715201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-09-07T10:49:29.154793Z","caller":"traceutil/trace.go:171","msg":"trace[1870726380] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:5093; }","duration":"232.754683ms","start":"2026-09-07T10:49:28.922031Z","end":"2026-09-07T10:49:29.154786Z","steps":["trace[1870726380] 'agreement among raft nodes before linearized reading' (duration: 232.684031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.154834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.763935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T10:49:29.154861Z","caller":"traceutil/trace.go:171","msg":"trace[1941643271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5093; }","duration":"208.832937ms","start":"2026-09-07T10:49:28.94602Z","end":"2026-09-07T10:49:29.154853Z","steps":["trace[1941643271] 'agreement among raft nodes before linearized reading' (duration: 208.755695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.15497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.147814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-07T10:49:29.154994Z","caller":"traceutil/trace.go:171","msg":"trace[2130099306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5093; }","duration":"238.182535ms","start":"2026-09-07T10:49:28.916804Z","end":"2026-09-07T10:49:29.154987Z","steps":["trace[2130099306] 'agreement among raft nodes before linearized reading' (duration: 238.093013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.155153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.057037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-07T10:49:29.155176Z","caller":"traceutil/trace.go:171","msg":"trace[752486224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5093; }","duration":"246.107639ms","start":"2026-09-07T10:49:28.909061Z","end":"2026-09-07T10:49:29.155169Z","steps":["trace[752486224] 'agreement among raft nodes before linearized reading' (duration: 246.039867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.15605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.640331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-07T10:49:29.156084Z","caller":"traceutil/trace.go:171","msg":"trace[307614869] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5093; }","duration":"320.696791ms","start":"2026-09-07T10:49:28.835377Z","end":"2026-09-07T10:49:29.156074Z","steps":["trace[307614869] 'agreement among raft nodes before linearized reading' (duration: 319.951786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.156109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:28.835364Z","time spent":"320.737583ms","remote":"127.0.0.1:56674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2207440,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-09-07T10:49:29.567523Z","caller":"traceutil/trace.go:171","msg":"trace[1060101658] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"173.1805ms","start":"2026-09-07T10:49:29.394322Z","end":"2026-09-07T10:49:29.567503Z","steps":["trace[1060101658] 'process raft request' (duration: 173.011096ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:29.639922Z","caller":"traceutil/trace.go:171","msg":"trace[877195845] linearizableReadLoop","detail":"{readStateIndex:5305; appliedIndex:5303; }","duration":"176.836545ms","start":"2026-09-07T10:49:29.463052Z","end":"2026-09-07T10:49:29.639888Z","steps":["trace[877195845] 'read index received' (duration: 104.257665ms)","trace[877195845] 'applied index is now lower than readState.Index' (duration: 72.57798ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:29.64001Z","caller":"traceutil/trace.go:171","msg":"trace[1363005796] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"189.66746ms","start":"2026-09-07T10:49:29.450334Z","end":"2026-09-07T10:49:29.640001Z","steps":["trace[1363005796] 'process raft request' (duration: 189.378274ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:29.64009Z","caller":"traceutil/trace.go:171","msg":"trace[141415933] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"139.502363ms","start":"2026-09-07T10:49:29.500553Z","end":"2026-09-07T10:49:29.640056Z","steps":["trace[141415933] 'process raft request' (duration: 139.267859ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.640335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.274674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-09-07T10:49:29.640365Z","caller":"traceutil/trace.go:171","msg":"trace[138576610] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5110; }","duration":"177.343936ms","start":"2026-09-07T10:49:29.463012Z","end":"2026-09-07T10:49:29.640356Z","steps":["trace[138576610] 'agreement among raft nodes before linearized reading' (duration: 177.214863ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:29.774529Z","caller":"traceutil/trace.go:171","msg":"trace[1487072692] transaction","detail":"{read_only:false; response_revision:5111; number_of_response:1; }","duration":"154.026584ms","start":"2026-09-07T10:49:29.620443Z","end":"2026-09-07T10:49:29.77447Z","steps":["trace[1487072692] 'process raft request' (duration: 121.118703ms)","trace[1487072692] 'compare' (duration: 32.583944ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:49:29.774651Z","caller":"traceutil/trace.go:171","msg":"trace[277081080] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"127.861903ms","start":"2026-09-07T10:49:29.646736Z","end":"2026-09-07T10:49:29.774597Z","steps":["trace[277081080] 'process raft request' (duration: 127.736611ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:49:29.774839Z","caller":"traceutil/trace.go:171","msg":"trace[256557184] linearizableReadLoop","detail":"{readStateIndex:5307; appliedIndex:5306; }","duration":"134.840887ms","start":"2026-09-07T10:49:29.639974Z","end":"2026-09-07T10:49:29.774815Z","steps":["trace[256557184] 'read index received' (duration: 101.59328ms)","trace[256557184] 'applied index is now lower than readState.Index' (duration: 33.245037ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:49:29.775211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.640291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:49:29.775273Z","caller":"traceutil/trace.go:171","msg":"trace[1310863885] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:5112; }","duration":"282.737564ms","start":"2026-09-07T10:49:29.49252Z","end":"2026-09-07T10:49:29.775258Z","steps":["trace[1310863885] 'agreement among raft nodes before linearized reading' (duration: 282.634952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:29.776257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.028918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-07T10:49:29.776299Z","caller":"traceutil/trace.go:171","msg":"trace[577976352] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5112; }","duration":"176.10943ms","start":"2026-09-07T10:49:29.600179Z","end":"2026-09-07T10:49:29.776288Z","steps":["trace[577976352] 'agreement among raft nodes before linearized reading' (duration: 174.774722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:40.812523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.082114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:49:40.813288Z","caller":"traceutil/trace.go:171","msg":"trace[631523066] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5247; }","duration":"315.930152ms","start":"2026-09-07T10:49:40.497328Z","end":"2026-09-07T10:49:40.813258Z","steps":["trace[631523066] 'count revisions from in-memory index tree' (duration: 314.962842ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:49:40.813769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:49:40.497301Z","time spent":"316.443692ms","remote":"127.0.0.1:33162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-09-07T10:49:58.678927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2885} {"level":"info","ts":"2026-09-07T10:49:58.759041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2885,"took":"78.836138ms","hash":98073922,"current-db-size-bytes":50495488,"current-db-size":"50 MB","current-db-size-in-use-bytes":27713536,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T10:49:58.75911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":98073922,"revision":2885,"compact-revision":-1} {"level":"warn","ts":"2026-09-07T10:53:39.241096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.640181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:53:39.24119Z","caller":"traceutil/trace.go:171","msg":"trace[1171333363] linearizableReadLoop","detail":"{readStateIndex:7277; appliedIndex:7276; }","duration":"320.162819ms","start":"2026-09-07T10:53:38.921012Z","end":"2026-09-07T10:53:39.241175Z","steps":["trace[1171333363] 'read index received' (duration: 207.287538ms)","trace[1171333363] 'applied index is now lower than readState.Index' (duration: 112.874421ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:53:39.241205Z","caller":"traceutil/trace.go:171","msg":"trace[1788395687] transaction","detail":"{read_only:false; response_revision:7016; number_of_response:1; }","duration":"345.592314ms","start":"2026-09-07T10:53:38.895598Z","end":"2026-09-07T10:53:39.24119Z","steps":["trace[1788395687] 'process raft request' (duration: 232.776066ms)","trace[1788395687] 'compare' (duration: 112.326429ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:53:39.241265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:53:38.895567Z","time spent":"345.673007ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:53:39.241341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.327215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-07T10:53:39.241385Z","caller":"traceutil/trace.go:171","msg":"trace[1589590295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7016; }","duration":"320.398358ms","start":"2026-09-07T10:53:38.920964Z","end":"2026-09-07T10:53:39.241363Z","steps":["trace[1589590295] 'agreement among raft nodes before linearized reading' (duration: 320.300834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:53:39.241415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:53:38.920938Z","time spent":"320.470671ms","remote":"127.0.0.1:56796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-07T10:53:39.241514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.214198ms","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-07T10:53:39.241544Z","caller":"traceutil/trace.go:171","msg":"trace[1962831844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7016; }","duration":"120.245269ms","start":"2026-09-07T10:53:39.121292Z","end":"2026-09-07T10:53:39.241537Z","steps":["trace[1962831844] 'agreement among raft nodes before linearized reading' (duration: 120.174326ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:53:39.241628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.28103ms","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-09-07T10:53:39.241651Z","caller":"traceutil/trace.go:171","msg":"trace[1036092726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7016; }","duration":"120.323622ms","start":"2026-09-07T10:53:39.121322Z","end":"2026-09-07T10:53:39.241646Z","steps":["trace[1036092726] 'agreement among raft nodes before linearized reading' (duration: 120.26464ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:54:58.688851Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5398} {"level":"info","ts":"2026-09-07T10:54:58.766144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5398,"took":"76.053428ms","hash":38058998,"current-db-size-bytes":50495488,"current-db-size":"50 MB","current-db-size-in-use-bytes":18870272,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-07T10:54:58.766211Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":38058998,"revision":5398,"compact-revision":2885}