{"level":"warn","ts":"2026-09-17T19:16:12.113104Z","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-17T19:16:12.113214Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.22: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.22:2380","--initial-cluster=instance=https://162.253.55.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.22: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-17T19:16:12.113296Z","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-17T19:16:12.113654Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-09-17T19:16:12.113678Z","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-17T19:16:12.11452Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"]} {"level":"info","ts":"2026-09-17T19:16:12.114617Z","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.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.22: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-17T19:16:12.119188Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.315709ms"} {"level":"info","ts":"2026-09-17T19:16:12.13355Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8608f4af3693a53b","cluster-id":"c541111188849f0e"} {"level":"info","ts":"2026-09-17T19:16:12.133627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=()"} {"level":"info","ts":"2026-09-17T19:16:12.133652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 0"} {"level":"info","ts":"2026-09-17T19:16:12.133666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8608f4af3693a53b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-17T19:16:12.133714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 1"} {"level":"info","ts":"2026-09-17T19:16:12.133832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"warn","ts":"2026-09-17T19:16:12.140545Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-17T19:16:12.141853Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-17T19:16:12.142987Z","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-17T19:16:12.146124Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8608f4af3693a53b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-17T19:16:12.146395Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8608f4af3693a53b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-17T19:16:12.146833Z","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-17T19:16:12.146875Z","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-17T19:16:12.146886Z","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-17T19:16:12.148634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"info","ts":"2026-09-17T19:16:12.148736Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","added-peer-id":"8608f4af3693a53b","added-peer-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-09-17T19:16:12.151392Z","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-17T19:16:12.151523Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-09-17T19:16:12.151579Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-09-17T19:16:12.151737Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8608f4af3693a53b","initial-advertise-peer-urls":["https://162.253.55.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-17T19:16:12.151783Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-17T19:16:12.234947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b is starting a new election at term 1"} {"level":"info","ts":"2026-09-17T19:16:12.234993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-17T19:16:12.235013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgPreVoteResp from 8608f4af3693a53b at term 1"} {"level":"info","ts":"2026-09-17T19:16:12.235031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became candidate at term 2"} {"level":"info","ts":"2026-09-17T19:16:12.235042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgVoteResp from 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-09-17T19:16:12.235057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became leader at term 2"} {"level":"info","ts":"2026-09-17T19:16:12.235074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8608f4af3693a53b elected leader 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-09-17T19:16:12.23598Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8608f4af3693a53b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.22:2379]}","request-path":"/0/members/8608f4af3693a53b/attributes","cluster-id":"c541111188849f0e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-17T19:16:12.236066Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T19:16:12.236178Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-17T19:16:12.236273Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-17T19:16:12.236391Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-17T19:16:12.236511Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-17T19:16:12.237693Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T19:16:12.237791Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T19:16:12.237894Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T19:16:12.238559Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.22:2379"} {"level":"info","ts":"2026-09-17T19:16:12.240188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-17T19:16:14.232146Z","caller":"traceutil/trace.go:171","msg":"trace[1337458612] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"413.494947ms","start":"2026-09-17T19:16:13.818608Z","end":"2026-09-17T19:16:14.232103Z","steps":["trace[1337458612] 'process raft request' (duration: 413.206814ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:16:14.232283Z","caller":"traceutil/trace.go:171","msg":"trace[678453798] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"342.501426ms","start":"2026-09-17T19:16:13.889714Z","end":"2026-09-17T19:16:14.232215Z","steps":["trace[678453798] 'process raft request' (duration: 342.300394ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:16:14.232318Z","caller":"traceutil/trace.go:171","msg":"trace[1205180735] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"342.359805ms","start":"2026-09-17T19:16:13.889911Z","end":"2026-09-17T19:16:14.232271Z","steps":["trace[1205180735] 'process raft request' (duration: 342.168413ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:16:14.232447Z","caller":"traceutil/trace.go:171","msg":"trace[906836600] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"343.703915ms","start":"2026-09-17T19:16:13.888681Z","end":"2026-09-17T19:16:14.232385Z","steps":["trace[906836600] 'process raft request' (duration: 343.264241ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:16:14.232391Z","caller":"traceutil/trace.go:171","msg":"trace[291757952] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"342.544126ms","start":"2026-09-17T19:16:13.889759Z","end":"2026-09-17T19:16:14.232303Z","steps":["trace[291757952] 'process raft request' (duration: 342.276714ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:16:14.232476Z","caller":"traceutil/trace.go:171","msg":"trace[1153577032] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"343.087831ms","start":"2026-09-17T19:16:13.889377Z","end":"2026-09-17T19:16:14.232465Z","steps":["trace[1153577032] 'process raft request' (duration: 342.599857ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:16:14.23254Z","caller":"traceutil/trace.go:171","msg":"trace[1135905144] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"342.559196ms","start":"2026-09-17T19:16:13.889966Z","end":"2026-09-17T19:16:14.232526Z","steps":["trace[1135905144] 'process raft request' (duration: 342.133773ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:16:14.232164Z","caller":"traceutil/trace.go:171","msg":"trace[1023838112] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"413.434587ms","start":"2026-09-17T19:16:13.818697Z","end":"2026-09-17T19:16:14.232131Z","steps":["trace[1023838112] 'read index received' (duration: 74.231681ms)","trace[1023838112] 'applied index is now lower than readState.Index' (duration: 339.201986ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:16:14.232295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.539898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-17T19:16:14.232922Z","caller":"traceutil/trace.go:171","msg":"trace[1424970325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9; }","duration":"414.238793ms","start":"2026-09-17T19:16:13.818667Z","end":"2026-09-17T19:16:14.232905Z","steps":["trace[1424970325] 'agreement among raft nodes before linearized reading' (duration: 413.503357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:16:14.232984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.818653Z","time spent":"414.309784ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-17T19:16:14.233121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.889707Z","time spent":"342.693907ms","remote":"127.0.0.1:49042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T19:16:14.23314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.889755Z","time spent":"342.767138ms","remote":"127.0.0.1:49042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T19:16:14.233328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.889906Z","time spent":"342.549817ms","remote":"127.0.0.1:49042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T19:16:14.233413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.889358Z","time spent":"343.151571ms","remote":"127.0.0.1:49042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T19:16:14.233449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.888652Z","time spent":"343.845127ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T19:16:14.233405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.818596Z","time spent":"413.68223ms","remote":"127.0.0.1:48614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:16:14.234421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.889962Z","time spent":"342.613936ms","remote":"127.0.0.1:49042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T19:16:14.237729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.167926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-17T19:16:14.237801Z","caller":"traceutil/trace.go:171","msg":"trace[327660786] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"347.264328ms","start":"2026-09-17T19:16:13.890515Z","end":"2026-09-17T19:16:14.23778Z","steps":["trace[327660786] 'agreement among raft nodes before linearized reading' (duration: 347.044526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:16:14.237893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.890505Z","time spent":"347.378879ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-09-17T19:16:14.237958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.849571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"warn","ts":"2026-09-17T19:16:14.237807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.978102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-17T19:16:14.238259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.805616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-17T19:16:14.238328Z","caller":"traceutil/trace.go:171","msg":"trace[1852698056] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"298.835046ms","start":"2026-09-17T19:16:13.939447Z","end":"2026-09-17T19:16:14.238282Z","steps":["trace[1852698056] 'agreement among raft nodes before linearized reading' (duration: 298.792195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:16:14.238333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.575907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-17T19:16:14.238406Z","caller":"traceutil/trace.go:171","msg":"trace[1055496947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"413.649287ms","start":"2026-09-17T19:16:13.824727Z","end":"2026-09-17T19:16:14.238376Z","steps":["trace[1055496947] 'agreement among raft nodes before linearized reading' (duration: 413.481236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:16:14.238452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.824716Z","time spent":"413.727928ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-17T19:16:14.238494Z","caller":"traceutil/trace.go:171","msg":"trace[372577296] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"319.751812ms","start":"2026-09-17T19:16:13.918727Z","end":"2026-09-17T19:16:14.238478Z","steps":["trace[372577296] 'process raft request' (duration: 318.685634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:16:14.238585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.918715Z","time spent":"319.826243ms","remote":"127.0.0.1:48722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T19:16:14.23856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.403929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-17T19:16:14.238643Z","caller":"traceutil/trace.go:171","msg":"trace[1571227812] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"264.48455ms","start":"2026-09-17T19:16:13.974142Z","end":"2026-09-17T19:16:14.238627Z","steps":["trace[1571227812] 'agreement among raft nodes before linearized reading' (duration: 264.378919ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:16:14.238566Z","caller":"traceutil/trace.go:171","msg":"trace[1756322060] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"413.543177ms","start":"2026-09-17T19:16:13.824713Z","end":"2026-09-17T19:16:14.238256Z","steps":["trace[1756322060] 'agreement among raft nodes before linearized reading' (duration: 412.7926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:16:14.238859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.824705Z","time spent":"414.134992ms","remote":"127.0.0.1:48926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-09-17T19:16:14.238008Z","caller":"traceutil/trace.go:171","msg":"trace[647412175] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"413.009192ms","start":"2026-09-17T19:16:13.824985Z","end":"2026-09-17T19:16:14.237994Z","steps":["trace[647412175] 'agreement among raft nodes before linearized reading' (duration: 412.831091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:16:14.239238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.824967Z","time spent":"414.210033ms","remote":"127.0.0.1:48722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-09-17T19:16:14.238145Z","caller":"traceutil/trace.go:171","msg":"trace[115756806] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"321.414516ms","start":"2026-09-17T19:16:13.916712Z","end":"2026-09-17T19:16:14.238126Z","steps":["trace[115756806] 'process raft request' (duration: 320.510828ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:16:14.239426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.916673Z","time spent":"322.715809ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T19:16:14.238163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.81823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-17T19:16:14.239507Z","caller":"traceutil/trace.go:171","msg":"trace[1221055778] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"351.195422ms","start":"2026-09-17T19:16:13.88829Z","end":"2026-09-17T19:16:14.239485Z","steps":["trace[1221055778] 'agreement among raft nodes before linearized reading' (duration: 349.840801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:16:14.239615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.88827Z","time spent":"351.331283ms","remote":"127.0.0.1:48664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-17T19:16:14.238161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.317895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-17T19:16:14.239745Z","caller":"traceutil/trace.go:171","msg":"trace[1567302170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"414.927519ms","start":"2026-09-17T19:16:13.824808Z","end":"2026-09-17T19:16:14.239735Z","steps":["trace[1567302170] 'agreement among raft nodes before linearized reading' (duration: 413.293984ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:16:14.239786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.824799Z","time spent":"414.97896ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-17T19:16:14.238206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.112682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.22\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-17T19:16:14.240325Z","caller":"traceutil/trace.go:171","msg":"trace[2112366473] range","detail":"{range_begin:/registry/masterleases/162.253.55.22; range_end:; response_count:0; response_revision:11; }","duration":"353.282041ms","start":"2026-09-17T19:16:13.887029Z","end":"2026-09-17T19:16:14.240311Z","steps":["trace[2112366473] 'agreement among raft nodes before linearized reading' (duration: 351.156382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:16:14.240367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:16:13.886996Z","time spent":"353.358192ms","remote":"127.0.0.1:48594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.22\" "} {"level":"info","ts":"2026-09-17T19:16:59.014602Z","caller":"traceutil/trace.go:171","msg":"trace[485777538] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"171.707713ms","start":"2026-09-17T19:16:58.842871Z","end":"2026-09-17T19:16:59.014579Z","steps":["trace[485777538] 'read index received' (duration: 171.564096ms)","trace[485777538] 'applied index is now lower than readState.Index' (duration: 142.447µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:16:59.014674Z","caller":"traceutil/trace.go:171","msg":"trace[805637681] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"250.891868ms","start":"2026-09-17T19:16:58.763757Z","end":"2026-09-17T19:16:59.014649Z","steps":["trace[805637681] 'process raft request' (duration: 250.687309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:16:59.01474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.85801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:16:59.014791Z","caller":"traceutil/trace.go:171","msg":"trace[562065045] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:514; }","duration":"171.949534ms","start":"2026-09-17T19:16:58.842813Z","end":"2026-09-17T19:16:59.014762Z","steps":["trace[562065045] 'agreement among raft nodes before linearized reading' (duration: 171.870081ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:17:05.784673Z","caller":"traceutil/trace.go:171","msg":"trace[1776426319] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"206.757045ms","start":"2026-09-17T19:17:05.573544Z","end":"2026-09-17T19:17:05.780301Z","steps":["trace[1776426319] 'process raft request' (duration: 205.297111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:06.074973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.136462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:17:06.075271Z","caller":"traceutil/trace.go:171","msg":"trace[1813864405] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"219.88268ms","start":"2026-09-17T19:17:05.855368Z","end":"2026-09-17T19:17:06.07525Z","steps":["trace[1813864405] 'read index received' (duration: 77.094µs)","trace[1813864405] 'applied index is now lower than readState.Index' (duration: 219.804076ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:17:06.075519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.108189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-17T19:17:06.075624Z","caller":"traceutil/trace.go:171","msg":"trace[779290469] range","detail":"{range_begin:/registry/masterleases/162.253.55.22; range_end:; response_count:1; response_revision:620; }","duration":"220.261585ms","start":"2026-09-17T19:17:05.855351Z","end":"2026-09-17T19:17:06.075612Z","steps":["trace[779290469] 'agreement among raft nodes before linearized reading' (duration: 220.065017ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:17:06.07594Z","caller":"traceutil/trace.go:171","msg":"trace[1555301151] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"408.688035ms","start":"2026-09-17T19:17:05.667207Z","end":"2026-09-17T19:17:06.075895Z","steps":["trace[1555301151] 'process raft request' (duration: 124.271598ms)","trace[1555301151] 'compare' (duration: 282.446393ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:17:06.076242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:05.667175Z","time spent":"408.858143ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:17:06.168121Z","caller":"traceutil/trace.go:171","msg":"trace[1153977520] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"299.069969ms","start":"2026-09-17T19:17:05.868987Z","end":"2026-09-17T19:17:06.168057Z","steps":["trace[1153977520] 'process raft request' (duration: 298.936373ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:17:06.224262Z","caller":"traceutil/trace.go:171","msg":"trace[1074018954] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"146.506022ms","start":"2026-09-17T19:17:06.077721Z","end":"2026-09-17T19:17:06.224227Z","steps":["trace[1074018954] 'read index received' (duration: 90.290592ms)","trace[1074018954] 'applied index is now lower than readState.Index' (duration: 56.2136ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:17:06.22465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.897438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qbhk9\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-17T19:17:06.224745Z","caller":"traceutil/trace.go:171","msg":"trace[1665170153] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qbhk9; range_end:; response_count:1; response_revision:621; }","duration":"147.039144ms","start":"2026-09-17T19:17:06.077686Z","end":"2026-09-17T19:17:06.224725Z","steps":["trace[1665170153] 'agreement among raft nodes before linearized reading' (duration: 146.717321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:06.823368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.02333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:17:06.82346Z","caller":"traceutil/trace.go:171","msg":"trace[1053196889] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"574.292925ms","start":"2026-09-17T19:17:06.249151Z","end":"2026-09-17T19:17:06.823443Z","steps":["trace[1053196889] 'read index received' (duration: 241.037435ms)","trace[1053196889] 'applied index is now lower than readState.Index' (duration: 333.25458ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:17:06.823523Z","caller":"traceutil/trace.go:171","msg":"trace[1857365692] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"576.097622ms","start":"2026-09-17T19:17:06.247415Z","end":"2026-09-17T19:17:06.823513Z","steps":["trace[1857365692] 'process raft request' (duration: 242.848262ms)","trace[1857365692] 'compare' (duration: 332.914775ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:17:06.823572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:06.247384Z","time spent":"576.160464ms","remote":"127.0.0.1:48732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:17:06.823657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.496013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-17T19:17:06.823709Z","caller":"traceutil/trace.go:171","msg":"trace[853449619] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:623; }","duration":"574.550965ms","start":"2026-09-17T19:17:06.249141Z","end":"2026-09-17T19:17:06.823692Z","steps":["trace[853449619] 'agreement among raft nodes before linearized reading' (duration: 574.400289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:06.823748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:06.249041Z","time spent":"574.699281ms","remote":"127.0.0.1:48594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-09-17T19:17:06.972268Z","caller":"traceutil/trace.go:171","msg":"trace[158693355] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"148.756848ms","start":"2026-09-17T19:17:06.823482Z","end":"2026-09-17T19:17:06.972238Z","steps":["trace[158693355] 'read index received' (duration: 147.8572ms)","trace[158693355] 'applied index is now lower than readState.Index' (duration: 898.558µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:17:06.972354Z","caller":"traceutil/trace.go:171","msg":"trace[865948461] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"334.525084ms","start":"2026-09-17T19:17:06.637805Z","end":"2026-09-17T19:17:06.972331Z","steps":["trace[865948461] 'process raft request' (duration: 333.759942ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:06.972554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.878646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-17T19:17:06.97258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:06.637752Z","time spent":"334.650409ms","remote":"127.0.0.1:48640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:17:06.972618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.467427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-17T19:17:06.972683Z","caller":"traceutil/trace.go:171","msg":"trace[1638088447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"301.54432ms","start":"2026-09-17T19:17:06.671122Z","end":"2026-09-17T19:17:06.972666Z","steps":["trace[1638088447] 'agreement among raft nodes before linearized reading' (duration: 301.281248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:06.972721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:06.671044Z","time spent":"301.669665ms","remote":"127.0.0.1:48788","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":"info","ts":"2026-09-17T19:17:06.972638Z","caller":"traceutil/trace.go:171","msg":"trace[1478984035] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:624; }","duration":"146.027961ms","start":"2026-09-17T19:17:06.826594Z","end":"2026-09-17T19:17:06.972622Z","steps":["trace[1478984035] 'agreement among raft nodes before linearized reading' (duration: 145.868104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:06.972852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.664685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-17T19:17:06.972961Z","caller":"traceutil/trace.go:171","msg":"trace[55674842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"301.827432ms","start":"2026-09-17T19:17:06.67111Z","end":"2026-09-17T19:17:06.972937Z","steps":["trace[55674842] 'agreement among raft nodes before linearized reading' (duration: 301.449056ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:06.973005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:06.671096Z","time spent":"301.902285ms","remote":"127.0.0.1:48788","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-17T19:17:22.559116Z","caller":"traceutil/trace.go:171","msg":"trace[1847071004] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"350.099931ms","start":"2026-09-17T19:17:22.208983Z","end":"2026-09-17T19:17:22.559083Z","steps":["trace[1847071004] 'read index received' (duration: 349.808659ms)","trace[1847071004] 'applied index is now lower than readState.Index' (duration: 289.952µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:17:22.559178Z","caller":"traceutil/trace.go:171","msg":"trace[1246688008] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"362.787619ms","start":"2026-09-17T19:17:22.196367Z","end":"2026-09-17T19:17:22.559154Z","steps":["trace[1246688008] 'process raft request' (duration: 362.499488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.559382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.347979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.559438Z","caller":"traceutil/trace.go:171","msg":"trace[254874824] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:787; }","duration":"350.495485ms","start":"2026-09-17T19:17:22.208928Z","end":"2026-09-17T19:17:22.559424Z","steps":["trace[254874824] 'agreement among raft nodes before linearized reading' (duration: 350.35494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.559479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:22.208902Z","time spent":"350.567237ms","remote":"127.0.0.1:51042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-09-17T19:17:22.559543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.502592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T19:17:22.559593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.945712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.559662Z","caller":"traceutil/trace.go:171","msg":"trace[1707490107] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:787; }","duration":"132.679568ms","start":"2026-09-17T19:17:22.426962Z","end":"2026-09-17T19:17:22.559642Z","steps":["trace[1707490107] 'agreement among raft nodes before linearized reading' (duration: 132.47877ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:17:22.559703Z","caller":"traceutil/trace.go:171","msg":"trace[503820346] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:787; }","duration":"296.108468ms","start":"2026-09-17T19:17:22.263573Z","end":"2026-09-17T19:17:22.559681Z","steps":["trace[503820346] 'agreement among raft nodes before linearized reading' (duration: 295.89685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.559666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:22.196335Z","time spent":"362.942545ms","remote":"127.0.0.1:48732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:17:22.559634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.769926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T19:17:22.55986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.912137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.559899Z","caller":"traceutil/trace.go:171","msg":"trace[767487474] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:787; }","duration":"132.993129ms","start":"2026-09-17T19:17:22.426894Z","end":"2026-09-17T19:17:22.559887Z","steps":["trace[767487474] 'agreement among raft nodes before linearized reading' (duration: 132.926427ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:17:22.559906Z","caller":"traceutil/trace.go:171","msg":"trace[146175629] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:787; }","duration":"327.056117ms","start":"2026-09-17T19:17:22.232821Z","end":"2026-09-17T19:17:22.559877Z","steps":["trace[146175629] 'agreement among raft nodes before linearized reading' (duration: 326.724045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.559969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:22.232812Z","time spent":"327.135839ms","remote":"127.0.0.1:51050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-09-17T19:17:22.560044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.374985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.560092Z","caller":"traceutil/trace.go:171","msg":"trace[2083816329] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:787; }","duration":"265.455798ms","start":"2026-09-17T19:17:22.294621Z","end":"2026-09-17T19:17:22.560076Z","steps":["trace[2083816329] 'agreement among raft nodes before linearized reading' (duration: 265.387436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.560154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.781591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.560203Z","caller":"traceutil/trace.go:171","msg":"trace[1725113970] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:787; }","duration":"296.852305ms","start":"2026-09-17T19:17:22.263329Z","end":"2026-09-17T19:17:22.560182Z","steps":["trace[1725113970] 'agreement among raft nodes before linearized reading' (duration: 296.788172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.560321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.208666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.560362Z","caller":"traceutil/trace.go:171","msg":"trace[791897805] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:787; }","duration":"266.266988ms","start":"2026-09-17T19:17:22.294083Z","end":"2026-09-17T19:17:22.56035Z","steps":["trace[791897805] 'agreement among raft nodes before linearized reading' (duration: 266.203115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.560531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.567012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T19:17:22.560315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.00909ms","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-17T19:17:22.560585Z","caller":"traceutil/trace.go:171","msg":"trace[1481822227] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:787; }","duration":"297.316042ms","start":"2026-09-17T19:17:22.263258Z","end":"2026-09-17T19:17:22.560574Z","steps":["trace[1481822227] 'agreement among raft nodes before linearized reading' (duration: 297.00944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.560629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.630403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.560659Z","caller":"traceutil/trace.go:171","msg":"trace[1501690989] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:787; }","duration":"328.678725ms","start":"2026-09-17T19:17:22.231972Z","end":"2026-09-17T19:17:22.560651Z","steps":["trace[1501690989] 'agreement among raft nodes before linearized reading' (duration: 328.634114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.560692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:22.231966Z","time spent":"328.718197ms","remote":"127.0.0.1:51050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-17T19:17:22.560546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.466936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.560798Z","caller":"traceutil/trace.go:171","msg":"trace[1614805986] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:787; }","duration":"266.756826ms","start":"2026-09-17T19:17:22.29403Z","end":"2026-09-17T19:17:22.560787Z","steps":["trace[1614805986] 'agreement among raft nodes before linearized reading' (duration: 266.483426ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:17:22.560588Z","caller":"traceutil/trace.go:171","msg":"trace[1316605501] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:787; }","duration":"328.652824ms","start":"2026-09-17T19:17:22.231916Z","end":"2026-09-17T19:17:22.560569Z","steps":["trace[1316605501] 'agreement among raft nodes before linearized reading' (duration: 328.565511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.561019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:22.231892Z","time spent":"329.09791ms","remote":"127.0.0.1:51050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-09-17T19:17:22.56141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.621752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T19:17:22.561416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.083598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.561464Z","caller":"traceutil/trace.go:171","msg":"trace[152135813] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:787; }","duration":"133.693544ms","start":"2026-09-17T19:17:22.427754Z","end":"2026-09-17T19:17:22.561447Z","steps":["trace[152135813] 'agreement among raft nodes before linearized reading' (duration: 133.611721ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:17:22.561471Z","caller":"traceutil/trace.go:171","msg":"trace[289656966] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:787; }","duration":"189.14727ms","start":"2026-09-17T19:17:22.372309Z","end":"2026-09-17T19:17:22.561456Z","steps":["trace[289656966] 'agreement among raft nodes before linearized reading' (duration: 189.053797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.561785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.968667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.561823Z","caller":"traceutil/trace.go:171","msg":"trace[1869562401] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:787; }","duration":"352.027078ms","start":"2026-09-17T19:17:22.209785Z","end":"2026-09-17T19:17:22.561812Z","steps":["trace[1869562401] 'agreement among raft nodes before linearized reading' (duration: 351.950176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.56185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:22.20978Z","time spent":"352.06159ms","remote":"127.0.0.1:51042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-09-17T19:17:22.562146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.872297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.562204Z","caller":"traceutil/trace.go:171","msg":"trace[1551433330] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:787; }","duration":"189.991261ms","start":"2026-09-17T19:17:22.372196Z","end":"2026-09-17T19:17:22.562187Z","steps":["trace[1551433330] 'agreement among raft nodes before linearized reading' (duration: 189.891367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.559373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.328869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.563976Z","caller":"traceutil/trace.go:171","msg":"trace[1399544777] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:787; }","duration":"354.973136ms","start":"2026-09-17T19:17:22.208985Z","end":"2026-09-17T19:17:22.563958Z","steps":["trace[1399544777] 'agreement among raft nodes before linearized reading' (duration: 350.335069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.564029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:17:22.208967Z","time spent":"355.042868ms","remote":"127.0.0.1:51042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-09-17T19:17:22.721471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.005324ms","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-17T19:17:22.721557Z","caller":"traceutil/trace.go:171","msg":"trace[1872930498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"124.17021ms","start":"2026-09-17T19:17:22.597366Z","end":"2026-09-17T19:17:22.721536Z","steps":["trace[1872930498] 'range keys from in-memory index tree' (duration: 123.739424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.722125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.522505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.722521Z","caller":"traceutil/trace.go:171","msg":"trace[1606447946] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:787; }","duration":"100.970581ms","start":"2026-09-17T19:17:22.62153Z","end":"2026-09-17T19:17:22.722501Z","steps":["trace[1606447946] 'range keys from in-memory index tree' (duration: 100.452363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.722275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.502105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.722678Z","caller":"traceutil/trace.go:171","msg":"trace[780466866] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:787; }","duration":"100.894738ms","start":"2026-09-17T19:17:22.62172Z","end":"2026-09-17T19:17:22.722615Z","steps":["trace[780466866] 'count revisions from in-memory index tree' (duration: 100.37858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:17:22.722469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.211213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:17:22.72298Z","caller":"traceutil/trace.go:171","msg":"trace[1633120010] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:787; }","duration":"100.684021ms","start":"2026-09-17T19:17:22.622247Z","end":"2026-09-17T19:17:22.722931Z","steps":["trace[1633120010] 'range keys from in-memory index tree' (duration: 100.13092ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:18:12.54539Z","caller":"traceutil/trace.go:171","msg":"trace[1962215224] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"107.40814ms","start":"2026-09-17T19:18:12.437958Z","end":"2026-09-17T19:18:12.545367Z","steps":["trace[1962215224] 'process raft request' (duration: 107.287598ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:18:19.659894Z","caller":"traceutil/trace.go:171","msg":"trace[639260350] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"108.413755ms","start":"2026-09-17T19:18:19.551451Z","end":"2026-09-17T19:18:19.659865Z","steps":["trace[639260350] 'process raft request' (duration: 108.23874ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:18:44.935011Z","caller":"traceutil/trace.go:171","msg":"trace[803607241] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1948; }","duration":"110.16275ms","start":"2026-09-17T19:18:44.824814Z","end":"2026-09-17T19:18:44.934977Z","steps":["trace[803607241] 'read index received' (duration: 16.364152ms)","trace[803607241] 'applied index is now lower than readState.Index' (duration: 93.797258ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:18:44.935042Z","caller":"traceutil/trace.go:171","msg":"trace[804901419] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"117.210415ms","start":"2026-09-17T19:18:44.817803Z","end":"2026-09-17T19:18:44.935013Z","steps":["trace[804901419] 'process raft request' (duration: 23.362907ms)","trace[804901419] 'compare' (duration: 93.658555ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:18:44.935157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.326604ms","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-17T19:18:44.935213Z","caller":"traceutil/trace.go:171","msg":"trace[1240179403] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1874; }","duration":"110.446347ms","start":"2026-09-17T19:18:44.824751Z","end":"2026-09-17T19:18:44.935198Z","steps":["trace[1240179403] 'agreement among raft nodes before linearized reading' (duration: 110.338395ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:18:45.671614Z","caller":"traceutil/trace.go:171","msg":"trace[2085394852] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1951; }","duration":"435.282976ms","start":"2026-09-17T19:18:45.236302Z","end":"2026-09-17T19:18:45.671585Z","steps":["trace[2085394852] 'read index received' (duration: 435.133592ms)","trace[2085394852] 'applied index is now lower than readState.Index' (duration: 148.004µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:18:45.671683Z","caller":"traceutil/trace.go:171","msg":"trace[746523598] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"482.815792ms","start":"2026-09-17T19:18:45.188839Z","end":"2026-09-17T19:18:45.671655Z","steps":["trace[746523598] 'process raft request' (duration: 482.586827ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:45.671796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.460079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:18:45.672098Z","caller":"traceutil/trace.go:171","msg":"trace[935146866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1877; }","duration":"435.845856ms","start":"2026-09-17T19:18:45.236229Z","end":"2026-09-17T19:18:45.672075Z","steps":["trace[935146866] 'agreement among raft nodes before linearized reading' (duration: 435.446989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:45.67215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:45.236201Z","time spent":"435.937928ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-17T19:18:45.672381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:45.18882Z","time spent":"483.221859ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:18:45.685957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.718022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-17T19:18:45.68601Z","caller":"traceutil/trace.go:171","msg":"trace[1713186373] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1878; }","duration":"226.826994ms","start":"2026-09-17T19:18:45.459167Z","end":"2026-09-17T19:18:45.685994Z","steps":["trace[1713186373] 'agreement among raft nodes before linearized reading' (duration: 226.6567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:48.177872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11906286721085797419,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-17T19:18:48.362634Z","caller":"traceutil/trace.go:171","msg":"trace[2027189945] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"724.877379ms","start":"2026-09-17T19:18:47.637732Z","end":"2026-09-17T19:18:48.362609Z","steps":["trace[2027189945] 'process raft request' (duration: 724.44722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:48.362794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:47.637712Z","time spent":"724.99099ms","remote":"127.0.0.1:48732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:18:48.691195Z","caller":"traceutil/trace.go:171","msg":"trace[2016082330] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1976; }","duration":"1.014432668s","start":"2026-09-17T19:18:47.676742Z","end":"2026-09-17T19:18:48.691174Z","steps":["trace[2016082330] 'read index received' (duration: 685.7439ms)","trace[2016082330] 'applied index is now lower than readState.Index' (duration: 328.687718ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:18:48.691225Z","caller":"traceutil/trace.go:171","msg":"trace[1260406749] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"1.000619957s","start":"2026-09-17T19:18:47.690552Z","end":"2026-09-17T19:18:48.691172Z","steps":["trace[1260406749] 'process raft request' (duration: 1.000274351s)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:48.691855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.625305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:18:48.692026Z","caller":"traceutil/trace.go:171","msg":"trace[967818893] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1904; }","duration":"573.846359ms","start":"2026-09-17T19:18:48.118164Z","end":"2026-09-17T19:18:48.69201Z","steps":["trace[967818893] 'agreement among raft nodes before linearized reading' (duration: 573.640686ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:48.692509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:48.118143Z","time spent":"574.356068ms","remote":"127.0.0.1:48942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-09-17T19:18:48.692394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.639603ms","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-17T19:18:48.692996Z","caller":"traceutil/trace.go:171","msg":"trace[1586452461] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1904; }","duration":"511.264245ms","start":"2026-09-17T19:18:48.181716Z","end":"2026-09-17T19:18:48.69298Z","steps":["trace[1586452461] 'agreement among raft nodes before linearized reading' (duration: 510.627293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:48.693084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:48.181697Z","time spent":"511.372528ms","remote":"127.0.0.1:60092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-17T19:18:48.691904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.66871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-17T19:18:48.692047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.863524ms","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-17T19:18:48.691365Z","caller":"traceutil/trace.go:171","msg":"trace[1861344342] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"384.71683ms","start":"2026-09-17T19:18:48.30664Z","end":"2026-09-17T19:18:48.691357Z","steps":["trace[1861344342] 'process raft request' (duration: 384.483065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:48.691567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014815675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-09-17T19:18:48.691953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:47.690526Z","time spent":"1.00120649s","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:18:48.691321Z","caller":"traceutil/trace.go:171","msg":"trace[1578722319] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"843.342971ms","start":"2026-09-17T19:18:47.847967Z","end":"2026-09-17T19:18:48.69131Z","steps":["trace[1578722319] 'process raft request' (duration: 843.067876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:48.692157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.759684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2df63880-9731-4175-894d-d93d8496fe70\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-09-17T19:18:48.692256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.152996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-17T19:18:48.692272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.270877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2df63880-9731-4175-894d-d93d8496fe70\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-09-17T19:18:48.69232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.342557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T19:18:48.692341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.26271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-17T19:18:48.693484Z","caller":"traceutil/trace.go:171","msg":"trace[820533572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1904; }","duration":"781.328652ms","start":"2026-09-17T19:18:47.912131Z","end":"2026-09-17T19:18:48.693459Z","steps":["trace[820533572] 'agreement among raft nodes before linearized reading' (duration: 779.809583ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:18:48.693756Z","caller":"traceutil/trace.go:171","msg":"trace[1805450089] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2df63880-9731-4175-894d-d93d8496fe70; range_end:; response_count:1; response_revision:1904; }","duration":"841.387985ms","start":"2026-09-17T19:18:47.852354Z","end":"2026-09-17T19:18:48.693742Z","steps":["trace[1805450089] 'agreement among raft nodes before linearized reading' (duration: 839.720644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:48.693738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:47.847945Z","time spent":"845.689136ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:18:48.693809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:47.852333Z","time spent":"841.459256ms","remote":"127.0.0.1:48732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2df63880-9731-4175-894d-d93d8496fe70\" "} {"level":"warn","ts":"2026-09-17T19:18:48.693858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:47.912113Z","time spent":"781.639527ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-17T19:18:48.693952Z","caller":"traceutil/trace.go:171","msg":"trace[952652538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1904; }","duration":"455.995798ms","start":"2026-09-17T19:18:48.237944Z","end":"2026-09-17T19:18:48.69394Z","steps":["trace[952652538] 'agreement among raft nodes before linearized reading' (duration: 454.360158ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:18:48.693961Z","caller":"traceutil/trace.go:171","msg":"trace[227185574] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2df63880-9731-4175-894d-d93d8496fe70; range_end:; response_count:1; response_revision:1904; }","duration":"324.982819ms","start":"2026-09-17T19:18:48.368961Z","end":"2026-09-17T19:18:48.693944Z","steps":["trace[227185574] 'agreement among raft nodes before linearized reading' (duration: 323.233217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:48.694019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:48.368942Z","time spent":"325.05778ms","remote":"127.0.0.1:48732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2df63880-9731-4175-894d-d93d8496fe70\" "} {"level":"warn","ts":"2026-09-17T19:18:48.694044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:48.237927Z","time spent":"456.076989ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-17T19:18:48.693532Z","caller":"traceutil/trace.go:171","msg":"trace[562009198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1904; }","duration":"983.329842ms","start":"2026-09-17T19:18:47.710183Z","end":"2026-09-17T19:18:48.693512Z","steps":["trace[562009198] 'agreement among raft nodes before linearized reading' (duration: 981.572409ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:18:48.693545Z","caller":"traceutil/trace.go:171","msg":"trace[1996500741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1904; }","duration":"1.016818793s","start":"2026-09-17T19:18:47.676696Z","end":"2026-09-17T19:18:48.693514Z","steps":["trace[1996500741] 'agreement among raft nodes before linearized reading' (duration: 1.014668942s)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:48.694312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:47.676676Z","time spent":"1.017553246s","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-17T19:18:48.694121Z","caller":"traceutil/trace.go:171","msg":"trace[1054249881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1904; }","duration":"977.087114ms","start":"2026-09-17T19:18:47.717021Z","end":"2026-09-17T19:18:48.694108Z","steps":["trace[1054249881] 'agreement among raft nodes before linearized reading' (duration: 975.25769ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:48.694424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:47.717002Z","time spent":"977.41174ms","remote":"127.0.0.1:48788","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-17T19:18:48.694144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:47.710165Z","time spent":"983.967243ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-17T19:18:48.69371Z","caller":"traceutil/trace.go:171","msg":"trace[1602606805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1904; }","duration":"960.616693ms","start":"2026-09-17T19:18:47.733071Z","end":"2026-09-17T19:18:48.693688Z","steps":["trace[1602606805] 'agreement among raft nodes before linearized reading' (duration: 959.113125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:48.694609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:47.733058Z","time spent":"961.539891ms","remote":"127.0.0.1:48788","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-17T19:18:48.694362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:48.306615Z","time spent":"387.653084ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:18:49.222142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.58423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:18:49.222246Z","caller":"traceutil/trace.go:171","msg":"trace[2099920974] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"522.559285ms","start":"2026-09-17T19:18:48.699667Z","end":"2026-09-17T19:18:49.222226Z","steps":["trace[2099920974] 'process raft request' (duration: 83.805312ms)","trace[2099920974] 'compare' (duration: 438.090911ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:18:49.222304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:48.699643Z","time spent":"522.633597ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:18:49.682773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.093942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:18:49.683081Z","caller":"traceutil/trace.go:171","msg":"trace[1081691922] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"982.241969ms","start":"2026-09-17T19:18:48.700772Z","end":"2026-09-17T19:18:49.683014Z","steps":["trace[1081691922] 'process raft request' (duration: 672.597786ms)","trace[1081691922] 'compare' (duration: 308.966549ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:18:49.683852Z","caller":"traceutil/trace.go:171","msg":"trace[1490808512] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"981.997654ms","start":"2026-09-17T19:18:48.701799Z","end":"2026-09-17T19:18:49.683797Z","steps":["trace[1490808512] 'process raft request' (duration: 981.104767ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:18:49.683879Z","caller":"traceutil/trace.go:171","msg":"trace[1909453997] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"981.312312ms","start":"2026-09-17T19:18:48.702536Z","end":"2026-09-17T19:18:49.683848Z","steps":["trace[1909453997] 'process raft request' (duration: 981.109948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:49.684011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:48.702524Z","time spent":"981.432203ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:18:49.684025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:48.701785Z","time spent":"982.152466ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:18:49.684032Z","caller":"traceutil/trace.go:171","msg":"trace[550366679] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"569.8558ms","start":"2026-09-17T19:18:49.114157Z","end":"2026-09-17T19:18:49.684013Z","steps":["trace[550366679] 'process raft request' (duration: 569.613706ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:18:49.684042Z","caller":"traceutil/trace.go:171","msg":"trace[1984745656] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"569.107785ms","start":"2026-09-17T19:18:49.114907Z","end":"2026-09-17T19:18:49.684015Z","steps":["trace[1984745656] 'process raft request' (duration: 568.946913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:49.684131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:49.114118Z","time spent":"569.954182ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:18:49.684165Z","caller":"traceutil/trace.go:171","msg":"trace[1723225600] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"981.596356ms","start":"2026-09-17T19:18:48.702558Z","end":"2026-09-17T19:18:49.684154Z","steps":["trace[1723225600] 'process raft request' (duration: 981.166268ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:18:49.684195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:49.114883Z","time spent":"569.229848ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:18:49.684216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:48.702545Z","time spent":"981.641427ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:18:49.684267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:18:48.700754Z","time spent":"982.518894ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:18:51.805488Z","caller":"traceutil/trace.go:171","msg":"trace[1962847678] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"103.483852ms","start":"2026-09-17T19:18:51.701979Z","end":"2026-09-17T19:18:51.805462Z","steps":["trace[1962847678] 'process raft request' (duration: 25.896009ms)","trace[1962847678] 'compare' (duration: 77.101765ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:18:51.805699Z","caller":"traceutil/trace.go:171","msg":"trace[616856889] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"102.020666ms","start":"2026-09-17T19:18:51.703668Z","end":"2026-09-17T19:18:51.805689Z","steps":["trace[616856889] 'process raft request' (duration: 101.514726ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:18:51.805853Z","caller":"traceutil/trace.go:171","msg":"trace[610823688] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"102.093837ms","start":"2026-09-17T19:18:51.70375Z","end":"2026-09-17T19:18:51.805844Z","steps":["trace[610823688] 'process raft request' (duration: 101.480395ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:18:51.805981Z","caller":"traceutil/trace.go:171","msg":"trace[1109557748] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"100.433787ms","start":"2026-09-17T19:18:51.705538Z","end":"2026-09-17T19:18:51.805972Z","steps":["trace[1109557748] 'process raft request' (duration: 99.720883ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:05.883392Z","caller":"traceutil/trace.go:171","msg":"trace[736529822] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"123.527052ms","start":"2026-09-17T19:19:05.759853Z","end":"2026-09-17T19:19:05.88338Z","steps":["trace[736529822] 'process raft request' (duration: 123.437411ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:05.971363Z","caller":"traceutil/trace.go:171","msg":"trace[530424515] linearizableReadLoop","detail":"{readStateIndex:2121; appliedIndex:2120; }","duration":"109.219834ms","start":"2026-09-17T19:19:05.862123Z","end":"2026-09-17T19:19:05.971343Z","steps":["trace[530424515] 'read index received' (duration: 21.264618ms)","trace[530424515] 'applied index is now lower than readState.Index' (duration: 87.954506ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:19:05.97157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.445289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-17T19:19:05.971616Z","caller":"traceutil/trace.go:171","msg":"trace[1462190516] range","detail":"{range_begin:/registry/masterleases/162.253.55.22; range_end:; response_count:1; response_revision:2041; }","duration":"109.53989ms","start":"2026-09-17T19:19:05.862065Z","end":"2026-09-17T19:19:05.971605Z","steps":["trace[1462190516] 'agreement among raft nodes before linearized reading' (duration: 109.394117ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:05.971847Z","caller":"traceutil/trace.go:171","msg":"trace[421144051] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"206.564012ms","start":"2026-09-17T19:19:05.765272Z","end":"2026-09-17T19:19:05.971836Z","steps":["trace[421144051] 'process raft request' (duration: 198.323889ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:06.95652Z","caller":"traceutil/trace.go:171","msg":"trace[2049681502] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"134.777329ms","start":"2026-09-17T19:19:06.821701Z","end":"2026-09-17T19:19:06.956478Z","steps":["trace[2049681502] 'process raft request' (duration: 134.612945ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:06.972536Z","caller":"traceutil/trace.go:171","msg":"trace[1891096131] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"147.038749ms","start":"2026-09-17T19:19:06.825471Z","end":"2026-09-17T19:19:06.97251Z","steps":["trace[1891096131] 'process raft request' (duration: 146.883447ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:23.840045Z","caller":"traceutil/trace.go:171","msg":"trace[1661658643] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"122.414223ms","start":"2026-09-17T19:19:23.717597Z","end":"2026-09-17T19:19:23.840012Z","steps":["trace[1661658643] 'process raft request' (duration: 122.148949ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:29.341063Z","caller":"traceutil/trace.go:171","msg":"trace[1270323639] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2257; }","duration":"207.764931ms","start":"2026-09-17T19:19:29.133214Z","end":"2026-09-17T19:19:29.340979Z","steps":["trace[1270323639] 'read index received' (duration: 164.571344ms)","trace[1270323639] 'applied index is now lower than readState.Index' (duration: 43.192077ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:19:29.341193Z","caller":"traceutil/trace.go:171","msg":"trace[1169510822] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"218.382749ms","start":"2026-09-17T19:19:29.122782Z","end":"2026-09-17T19:19:29.341164Z","steps":["trace[1169510822] 'process raft request' (duration: 175.050581ms)","trace[1169510822] 'compare' (duration: 42.978463ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:19:29.341582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.357879ms","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-17T19:19:29.341627Z","caller":"traceutil/trace.go:171","msg":"trace[476886985] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"208.511632ms","start":"2026-09-17T19:19:29.1331Z","end":"2026-09-17T19:19:29.341612Z","steps":["trace[476886985] 'agreement among raft nodes before linearized reading' (duration: 208.121006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:29.341683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.658406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:19:29.341767Z","caller":"traceutil/trace.go:171","msg":"trace[377038604] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2172; }","duration":"101.753437ms","start":"2026-09-17T19:19:29.239997Z","end":"2026-09-17T19:19:29.341751Z","steps":["trace[377038604] 'agreement among raft nodes before linearized reading' (duration: 101.632684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:29.679908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.021195ms","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-17T19:19:29.679984Z","caller":"traceutil/trace.go:171","msg":"trace[660254067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2172; }","duration":"152.148338ms","start":"2026-09-17T19:19:29.527818Z","end":"2026-09-17T19:19:29.679966Z","steps":["trace[660254067] 'range keys from in-memory index tree' (duration: 151.804641ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:29.68881Z","caller":"traceutil/trace.go:171","msg":"trace[1465311117] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"130.340821ms","start":"2026-09-17T19:19:29.558447Z","end":"2026-09-17T19:19:29.688788Z","steps":["trace[1465311117] 'process raft request' (duration: 129.944865ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:30.293197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.38711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T19:19:30.293315Z","caller":"traceutil/trace.go:171","msg":"trace[613511984] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2174; }","duration":"489.531613ms","start":"2026-09-17T19:19:29.803731Z","end":"2026-09-17T19:19:30.293263Z","steps":["trace[613511984] 'count revisions from in-memory index tree' (duration: 489.271749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:30.293386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:19:29.803707Z","time spent":"489.659914ms","remote":"127.0.0.1:42448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-09-17T19:19:30.29405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.775733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:19:30.294235Z","caller":"traceutil/trace.go:171","msg":"trace[817441876] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2174; }","duration":"337.958346ms","start":"2026-09-17T19:19:29.956254Z","end":"2026-09-17T19:19:30.294212Z","steps":["trace[817441876] 'count revisions from in-memory index tree' (duration: 337.68548ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:30.294425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:19:29.956199Z","time spent":"338.158828ms","remote":"127.0.0.1:48768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-09-17T19:19:30.294737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.743026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"info","ts":"2026-09-17T19:19:30.294796Z","caller":"traceutil/trace.go:171","msg":"trace[660733987] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2174; }","duration":"331.827478ms","start":"2026-09-17T19:19:29.962959Z","end":"2026-09-17T19:19:30.294787Z","steps":["trace[660733987] 'range keys from in-memory index tree' (duration: 331.651445ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:30.294835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:19:29.962948Z","time spent":"331.878948ms","remote":"127.0.0.1:48732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12406,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-17T19:19:30.295569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.172341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:19:30.295665Z","caller":"traceutil/trace.go:171","msg":"trace[1113657038] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"307.891767ms","start":"2026-09-17T19:19:29.987762Z","end":"2026-09-17T19:19:30.295653Z","steps":["trace[1113657038] 'process raft request' (duration: 112.007714ms)","trace[1113657038] 'compare' (duration: 193.995443ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:19:30.295739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:19:29.987746Z","time spent":"307.967549ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:19:30.295728Z","caller":"traceutil/trace.go:171","msg":"trace[1666461374] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"251.351161ms","start":"2026-09-17T19:19:30.044282Z","end":"2026-09-17T19:19:30.295633Z","steps":["trace[1666461374] 'read index received' (duration: 55.475269ms)","trace[1666461374] 'applied index is now lower than readState.Index' (duration: 195.873922ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:19:30.296579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.295817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T19:19:30.29663Z","caller":"traceutil/trace.go:171","msg":"trace[2024726911] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2175; }","duration":"252.387908ms","start":"2026-09-17T19:19:30.044231Z","end":"2026-09-17T19:19:30.296619Z","steps":["trace[2024726911] 'agreement among raft nodes before linearized reading' (duration: 251.525734ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:30.309766Z","caller":"traceutil/trace.go:171","msg":"trace[1972430788] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"232.652755ms","start":"2026-09-17T19:19:30.077092Z","end":"2026-09-17T19:19:30.309745Z","steps":["trace[1972430788] 'process raft request' (duration: 232.34747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:30.310028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.955559ms","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-17T19:19:30.310103Z","caller":"traceutil/trace.go:171","msg":"trace[467734655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2176; }","duration":"178.036549ms","start":"2026-09-17T19:19:30.132028Z","end":"2026-09-17T19:19:30.310065Z","steps":["trace[467734655] 'agreement among raft nodes before linearized reading' (duration: 177.865217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:30.310241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.486037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T19:19:30.310356Z","caller":"traceutil/trace.go:171","msg":"trace[1962745591] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2176; }","duration":"125.626669ms","start":"2026-09-17T19:19:30.184714Z","end":"2026-09-17T19:19:30.310341Z","steps":["trace[1962745591] 'agreement among raft nodes before linearized reading' (duration: 125.407395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:30.310882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.505931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-17T19:19:30.310943Z","caller":"traceutil/trace.go:171","msg":"trace[435233907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2176; }","duration":"165.580022ms","start":"2026-09-17T19:19:30.145347Z","end":"2026-09-17T19:19:30.310927Z","steps":["trace[435233907] 'agreement among raft nodes before linearized reading' (duration: 165.368428ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:30.310942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.573158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-09-17T19:19:30.310993Z","caller":"traceutil/trace.go:171","msg":"trace[2047436627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2176; }","duration":"166.625638ms","start":"2026-09-17T19:19:30.144355Z","end":"2026-09-17T19:19:30.31098Z","steps":["trace[2047436627] 'agreement among raft nodes before linearized reading' (duration: 166.500686ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:30.311062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.562171ms","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-17T19:19:30.311867Z","caller":"traceutil/trace.go:171","msg":"trace[753394530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2176; }","duration":"195.419244ms","start":"2026-09-17T19:19:30.116427Z","end":"2026-09-17T19:19:30.311847Z","steps":["trace[753394530] 'agreement among raft nodes before linearized reading' (duration: 193.836249ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:30.462839Z","caller":"traceutil/trace.go:171","msg":"trace[1154722568] linearizableReadLoop","detail":"{readStateIndex:2266; appliedIndex:2263; }","duration":"136.957139ms","start":"2026-09-17T19:19:30.325862Z","end":"2026-09-17T19:19:30.462819Z","steps":["trace[1154722568] 'read index received' (duration: 13.27466ms)","trace[1154722568] 'applied index is now lower than readState.Index' (duration: 123.681309ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:19:30.463335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.421867ms","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-17T19:19:30.463529Z","caller":"traceutil/trace.go:171","msg":"trace[1904363635] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2180; }","duration":"137.725772ms","start":"2026-09-17T19:19:30.32578Z","end":"2026-09-17T19:19:30.463505Z","steps":["trace[1904363635] 'agreement among raft nodes before linearized reading' (duration: 137.320045ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:30.4638Z","caller":"traceutil/trace.go:171","msg":"trace[1716923629] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"145.561015ms","start":"2026-09-17T19:19:30.318225Z","end":"2026-09-17T19:19:30.463786Z","steps":["trace[1716923629] 'process raft request' (duration: 80.692218ms)","trace[1716923629] 'compare' (duration: 63.613258ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:19:30.463946Z","caller":"traceutil/trace.go:171","msg":"trace[1236289678] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"145.503455ms","start":"2026-09-17T19:19:30.318432Z","end":"2026-09-17T19:19:30.463935Z","steps":["trace[1236289678] 'process raft request' (duration: 144.280605ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:30.464064Z","caller":"traceutil/trace.go:171","msg":"trace[1860004142] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"145.424573ms","start":"2026-09-17T19:19:30.318631Z","end":"2026-09-17T19:19:30.464055Z","steps":["trace[1860004142] 'process raft request' (duration: 144.151823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:30.463369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.323251ms","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-17T19:19:30.464206Z","caller":"traceutil/trace.go:171","msg":"trace[329281541] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2180; }","duration":"115.220375ms","start":"2026-09-17T19:19:30.348976Z","end":"2026-09-17T19:19:30.464196Z","steps":["trace[329281541] 'agreement among raft nodes before linearized reading' (duration: 114.23991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:31.068851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.49687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-17T19:19:31.069021Z","caller":"traceutil/trace.go:171","msg":"trace[171268295] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2180; }","duration":"323.793845ms","start":"2026-09-17T19:19:30.745203Z","end":"2026-09-17T19:19:31.068997Z","steps":["trace[171268295] 'range keys from in-memory index tree' (duration: 323.322118ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:31.069651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:19:30.745184Z","time spent":"324.448536ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-09-17T19:19:31.069071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.780992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T19:19:31.069722Z","caller":"traceutil/trace.go:171","msg":"trace[1573778457] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2180; }","duration":"248.470583ms","start":"2026-09-17T19:19:30.821231Z","end":"2026-09-17T19:19:31.069702Z","steps":["trace[1573778457] 'count revisions from in-memory index tree' (duration: 247.690551ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:31.399545Z","caller":"traceutil/trace.go:171","msg":"trace[1442767119] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2269; }","duration":"262.118509ms","start":"2026-09-17T19:19:31.137409Z","end":"2026-09-17T19:19:31.399527Z","steps":["trace[1442767119] 'read index received' (duration: 229.582895ms)","trace[1442767119] 'applied index is now lower than readState.Index' (duration: 32.534514ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:19:31.399634Z","caller":"traceutil/trace.go:171","msg":"trace[1771390023] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"273.818183ms","start":"2026-09-17T19:19:31.125807Z","end":"2026-09-17T19:19:31.399625Z","steps":["trace[1771390023] 'process raft request' (duration: 241.212129ms)","trace[1771390023] 'compare' (duration: 32.292279ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:19:31.399718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.299321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T19:19:31.399879Z","caller":"traceutil/trace.go:171","msg":"trace[552837214] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2183; }","duration":"262.509736ms","start":"2026-09-17T19:19:31.137357Z","end":"2026-09-17T19:19:31.399867Z","steps":["trace[552837214] 'agreement among raft nodes before linearized reading' (duration: 262.315882ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:31.702851Z","caller":"traceutil/trace.go:171","msg":"trace[1689489928] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"355.673426ms","start":"2026-09-17T19:19:31.347164Z","end":"2026-09-17T19:19:31.702838Z","steps":["trace[1689489928] 'process raft request' (duration: 355.495894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:31.702998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:19:31.347146Z","time spent":"355.757887ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:19:31.703174Z","caller":"traceutil/trace.go:171","msg":"trace[1103419345] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"303.208348ms","start":"2026-09-17T19:19:31.399588Z","end":"2026-09-17T19:19:31.702797Z","steps":["trace[1103419345] 'read index received' (duration: 303.117026ms)","trace[1103419345] 'applied index is now lower than readState.Index' (duration: 90.152µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:19:31.703292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.047859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:19:31.703367Z","caller":"traceutil/trace.go:171","msg":"trace[993412100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2184; }","duration":"466.144531ms","start":"2026-09-17T19:19:31.237186Z","end":"2026-09-17T19:19:31.703331Z","steps":["trace[993412100] 'agreement among raft nodes before linearized reading' (duration: 466.058109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:31.70398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:19:31.237173Z","time spent":"466.240402ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-17T19:19:31.724744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.3415ms","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-17T19:19:31.724834Z","caller":"traceutil/trace.go:171","msg":"trace[1025358188] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2185; }","duration":"253.436892ms","start":"2026-09-17T19:19:31.471374Z","end":"2026-09-17T19:19:31.724811Z","steps":["trace[1025358188] 'agreement among raft nodes before linearized reading' (duration: 253.221227ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:19:31.724833Z","caller":"traceutil/trace.go:171","msg":"trace[131893584] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"274.544185ms","start":"2026-09-17T19:19:31.450048Z","end":"2026-09-17T19:19:31.724592Z","steps":["trace[131893584] 'process raft request' (duration: 274.395203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:31.724768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.753086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-17T19:19:31.725016Z","caller":"traceutil/trace.go:171","msg":"trace[557915806] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2185; }","duration":"321.029549ms","start":"2026-09-17T19:19:31.403974Z","end":"2026-09-17T19:19:31.725003Z","steps":["trace[557915806] 'agreement among raft nodes before linearized reading' (duration: 320.672953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:31.725052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:19:31.403962Z","time spent":"321.077ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-17T19:19:32.054969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.094942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:19:32.055423Z","caller":"traceutil/trace.go:171","msg":"trace[1826225314] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"324.584223ms","start":"2026-09-17T19:19:31.73082Z","end":"2026-09-17T19:19:32.055404Z","steps":["trace[1826225314] 'process raft request' (duration: 324.249967ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:19:32.055517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:19:31.730804Z","time spent":"324.672434ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:19:32.055366Z","caller":"traceutil/trace.go:171","msg":"trace[1945092223] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"325.430317ms","start":"2026-09-17T19:19:31.729904Z","end":"2026-09-17T19:19:32.055335Z","steps":["trace[1945092223] 'process raft request' (duration: 73.890217ms)","trace[1945092223] 'compare' (duration: 250.89698ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:19:32.056057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:19:31.729885Z","time spent":"325.795712ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:20:00.716728Z","caller":"traceutil/trace.go:171","msg":"trace[1396981675] transaction","detail":"{read_only:false; response_revision:2352; number_of_response:1; }","duration":"103.732262ms","start":"2026-09-17T19:20:00.61297Z","end":"2026-09-17T19:20:00.716702Z","steps":["trace[1396981675] 'process raft request' (duration: 67.963768ms)","trace[1396981675] 'compare' (duration: 35.659572ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:20:04.30837Z","caller":"traceutil/trace.go:171","msg":"trace[1819092888] transaction","detail":"{read_only:false; response_revision:2370; number_of_response:1; }","duration":"151.617615ms","start":"2026-09-17T19:20:04.156713Z","end":"2026-09-17T19:20:04.308331Z","steps":["trace[1819092888] 'process raft request' (duration: 60.388648ms)","trace[1819092888] 'compare' (duration: 90.922063ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:20:04.308395Z","caller":"traceutil/trace.go:171","msg":"trace[636567686] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"151.608205ms","start":"2026-09-17T19:20:04.156764Z","end":"2026-09-17T19:20:04.308372Z","steps":["trace[636567686] 'process raft request' (duration: 151.474273ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:04.308892Z","caller":"traceutil/trace.go:171","msg":"trace[1659879333] transaction","detail":"{read_only:false; response_revision:2373; number_of_response:1; }","duration":"151.158018ms","start":"2026-09-17T19:20:04.157682Z","end":"2026-09-17T19:20:04.30884Z","steps":["trace[1659879333] 'process raft request' (duration: 150.871964ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:04.309689Z","caller":"traceutil/trace.go:171","msg":"trace[1563916921] transaction","detail":"{read_only:false; response_revision:2372; number_of_response:1; }","duration":"152.542728ms","start":"2026-09-17T19:20:04.157093Z","end":"2026-09-17T19:20:04.309636Z","steps":["trace[1563916921] 'process raft request' (duration: 151.223569ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:04.328503Z","caller":"traceutil/trace.go:171","msg":"trace[1662460284] linearizableReadLoop","detail":"{readStateIndex:2467; appliedIndex:2462; }","duration":"111.554473ms","start":"2026-09-17T19:20:04.216925Z","end":"2026-09-17T19:20:04.32848Z","steps":["trace[1662460284] 'read index received' (duration: 193.923µs)","trace[1662460284] 'applied index is now lower than readState.Index' (duration: 111.35951ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:20:04.328571Z","caller":"traceutil/trace.go:171","msg":"trace[1558478281] transaction","detail":"{read_only:false; response_revision:2374; number_of_response:1; }","duration":"161.903195ms","start":"2026-09-17T19:20:04.166617Z","end":"2026-09-17T19:20:04.32852Z","steps":["trace[1558478281] 'process raft request' (duration: 161.690942ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:04.328775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.830166ms","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-17T19:20:04.328839Z","caller":"traceutil/trace.go:171","msg":"trace[2090380764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2374; }","duration":"111.910037ms","start":"2026-09-17T19:20:04.216915Z","end":"2026-09-17T19:20:04.328825Z","steps":["trace[2090380764] 'agreement among raft nodes before linearized reading' (duration: 111.660834ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:04.745826Z","caller":"traceutil/trace.go:171","msg":"trace[277120037] linearizableReadLoop","detail":"{readStateIndex:2471; appliedIndex:2470; }","duration":"115.352338ms","start":"2026-09-17T19:20:04.630411Z","end":"2026-09-17T19:20:04.745763Z","steps":["trace[277120037] 'read index received' (duration: 27.030954ms)","trace[277120037] 'applied index is now lower than readState.Index' (duration: 88.319874ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:20:04.746002Z","caller":"traceutil/trace.go:171","msg":"trace[1058896730] transaction","detail":"{read_only:false; response_revision:2378; number_of_response:1; }","duration":"173.236369ms","start":"2026-09-17T19:20:04.572745Z","end":"2026-09-17T19:20:04.745981Z","steps":["trace[1058896730] 'process raft request' (duration: 84.630231ms)","trace[1058896730] 'compare' (duration: 88.110921ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:20:04.746177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.760074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-17T19:20:04.746259Z","caller":"traceutil/trace.go:171","msg":"trace[1180534588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2378; }","duration":"115.907596ms","start":"2026-09-17T19:20:04.630336Z","end":"2026-09-17T19:20:04.746243Z","steps":["trace[1180534588] 'agreement among raft nodes before linearized reading' (duration: 115.689563ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:16.438854Z","caller":"traceutil/trace.go:171","msg":"trace[74482715] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"141.529554ms","start":"2026-09-17T19:20:16.297273Z","end":"2026-09-17T19:20:16.438803Z","steps":["trace[74482715] 'process raft request' (duration: 54.924932ms)","trace[74482715] 'compare' (duration: 86.291308ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:20:16.44938Z","caller":"traceutil/trace.go:171","msg":"trace[1049479966] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"150.866086ms","start":"2026-09-17T19:20:16.298485Z","end":"2026-09-17T19:20:16.449351Z","steps":["trace[1049479966] 'process raft request' (duration: 150.666034ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:16.617413Z","caller":"traceutil/trace.go:171","msg":"trace[769247090] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"160.196061ms","start":"2026-09-17T19:20:16.457188Z","end":"2026-09-17T19:20:16.617384Z","steps":["trace[769247090] 'process raft request' (duration: 103.847029ms)","trace[769247090] 'compare' (duration: 56.181879ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:20:16.648408Z","caller":"traceutil/trace.go:171","msg":"trace[821298280] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"110.601034ms","start":"2026-09-17T19:20:16.537769Z","end":"2026-09-17T19:20:16.64837Z","steps":["trace[821298280] 'process raft request' (duration: 110.523943ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:16.701559Z","caller":"traceutil/trace.go:171","msg":"trace[871119708] transaction","detail":"{read_only:false; response_revision:2457; number_of_response:1; }","duration":"104.184442ms","start":"2026-09-17T19:20:16.597349Z","end":"2026-09-17T19:20:16.701533Z","steps":["trace[871119708] 'process raft request' (duration: 104.05297ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:16.701559Z","caller":"traceutil/trace.go:171","msg":"trace[1396025414] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"125.792131ms","start":"2026-09-17T19:20:16.575722Z","end":"2026-09-17T19:20:16.701515Z","steps":["trace[1396025414] 'process raft request' (duration: 125.561077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:16.701768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.743345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/memcached-memcached\" ","response":"range_response_count:1 size:6882"} {"level":"info","ts":"2026-09-17T19:20:16.701834Z","caller":"traceutil/trace.go:171","msg":"trace[984454787] range","detail":"{range_begin:/registry/deployments/openstack/memcached-memcached; range_end:; response_count:1; response_revision:2457; }","duration":"157.857527ms","start":"2026-09-17T19:20:16.54396Z","end":"2026-09-17T19:20:16.701817Z","steps":["trace[984454787] 'agreement among raft nodes before linearized reading' (duration: 157.490462ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:16.760458Z","caller":"traceutil/trace.go:171","msg":"trace[551524139] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"105.643272ms","start":"2026-09-17T19:20:16.654764Z","end":"2026-09-17T19:20:16.760407Z","steps":["trace[551524139] 'process raft request' (duration: 105.087585ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:16.760488Z","caller":"traceutil/trace.go:171","msg":"trace[1073066538] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"102.363396ms","start":"2026-09-17T19:20:16.658104Z","end":"2026-09-17T19:20:16.760467Z","steps":["trace[1073066538] 'process raft request' (duration: 102.272895ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:16.760953Z","caller":"traceutil/trace.go:171","msg":"trace[1403320376] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"105.858465ms","start":"2026-09-17T19:20:16.65507Z","end":"2026-09-17T19:20:16.760929Z","steps":["trace[1403320376] 'process raft request' (duration: 105.241667ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:16.819987Z","caller":"traceutil/trace.go:171","msg":"trace[1310684403] transaction","detail":"{read_only:false; response_revision:2461; number_of_response:1; }","duration":"108.884848ms","start":"2026-09-17T19:20:16.711069Z","end":"2026-09-17T19:20:16.819954Z","steps":["trace[1310684403] 'process raft request' (duration: 108.579834ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:19.844625Z","caller":"traceutil/trace.go:171","msg":"trace[468024455] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"136.611395ms","start":"2026-09-17T19:20:19.707957Z","end":"2026-09-17T19:20:19.844568Z","steps":["trace[468024455] 'process raft request' (duration: 128.804253ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:26.812007Z","caller":"traceutil/trace.go:171","msg":"trace[1616990958] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"179.575285ms","start":"2026-09-17T19:20:26.6324Z","end":"2026-09-17T19:20:26.811975Z","steps":["trace[1616990958] 'process raft request' (duration: 179.314772ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:26.812136Z","caller":"traceutil/trace.go:171","msg":"trace[1505435994] linearizableReadLoop","detail":"{readStateIndex:2675; appliedIndex:2672; }","duration":"126.942188ms","start":"2026-09-17T19:20:26.685183Z","end":"2026-09-17T19:20:26.812125Z","steps":["trace[1505435994] 'read index received' (duration: 126.548413ms)","trace[1505435994] 'applied index is now lower than readState.Index' (duration: 392.515µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:20:26.812046Z","caller":"traceutil/trace.go:171","msg":"trace[1299977887] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"178.979488ms","start":"2026-09-17T19:20:26.633035Z","end":"2026-09-17T19:20:26.812015Z","steps":["trace[1299977887] 'process raft request' (duration: 178.820205ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:26.812253Z","caller":"traceutil/trace.go:171","msg":"trace[2036001472] transaction","detail":"{read_only:false; response_revision:2575; number_of_response:1; }","duration":"176.980588ms","start":"2026-09-17T19:20:26.635247Z","end":"2026-09-17T19:20:26.812228Z","steps":["trace[2036001472] 'process raft request' (duration: 176.700355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:26.81262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.425645ms","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-17T19:20:26.812696Z","caller":"traceutil/trace.go:171","msg":"trace[18520118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2575; }","duration":"127.555197ms","start":"2026-09-17T19:20:26.685122Z","end":"2026-09-17T19:20:26.812677Z","steps":["trace[18520118] 'agreement among raft nodes before linearized reading' (duration: 127.354194ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:29.313896Z","caller":"traceutil/trace.go:171","msg":"trace[1441636741] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"485.877941ms","start":"2026-09-17T19:20:28.827996Z","end":"2026-09-17T19:20:29.313874Z","steps":["trace[1441636741] 'process raft request' (duration: 485.731149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:29.314059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:28.827967Z","time spent":"485.992142ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:20:29.320338Z","caller":"traceutil/trace.go:171","msg":"trace[452971802] linearizableReadLoop","detail":"{readStateIndex:2692; appliedIndex:2690; }","duration":"456.682844ms","start":"2026-09-17T19:20:28.863626Z","end":"2026-09-17T19:20:29.320309Z","steps":["trace[452971802] 'read index received' (duration: 450.133112ms)","trace[452971802] 'applied index is now lower than readState.Index' (duration: 6.548512ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:20:29.320507Z","caller":"traceutil/trace.go:171","msg":"trace[1651399209] transaction","detail":"{read_only:false; response_revision:2592; number_of_response:1; }","duration":"490.962752ms","start":"2026-09-17T19:20:28.829526Z","end":"2026-09-17T19:20:29.320489Z","steps":["trace[1651399209] 'process raft request' (duration: 490.664337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:29.320587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:28.829498Z","time spent":"491.048953ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:20:29.320606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.960167ms","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-17T19:20:29.32066Z","caller":"traceutil/trace.go:171","msg":"trace[1262356434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2592; }","duration":"457.068689ms","start":"2026-09-17T19:20:28.863579Z","end":"2026-09-17T19:20:29.320647Z","steps":["trace[1262356434] 'agreement among raft nodes before linearized reading' (duration: 456.909337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:29.320695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:28.863557Z","time spent":"457.12959ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-17T19:20:29.320849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.396635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-17T19:20:29.320877Z","caller":"traceutil/trace.go:171","msg":"trace[783738850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2592; }","duration":"397.449525ms","start":"2026-09-17T19:20:28.92342Z","end":"2026-09-17T19:20:29.320869Z","steps":["trace[783738850] 'agreement among raft nodes before linearized reading' (duration: 397.372354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:29.320903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:28.923381Z","time spent":"397.516667ms","remote":"127.0.0.1:48788","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-17T19:20:29.320978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.694921ms","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-17T19:20:29.321019Z","caller":"traceutil/trace.go:171","msg":"trace[1964425139] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2592; }","duration":"342.764502ms","start":"2026-09-17T19:20:28.978243Z","end":"2026-09-17T19:20:29.321008Z","steps":["trace[1964425139] 'agreement among raft nodes before linearized reading' (duration: 342.64135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:29.321049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:28.97823Z","time spent":"342.813032ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-17T19:20:29.321144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.121018ms","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-17T19:20:29.321177Z","caller":"traceutil/trace.go:171","msg":"trace[1434819775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2592; }","duration":"371.175598ms","start":"2026-09-17T19:20:28.949993Z","end":"2026-09-17T19:20:29.321169Z","steps":["trace[1434819775] 'agreement among raft nodes before linearized reading' (duration: 371.090287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:29.321204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:28.949983Z","time spent":"371.215579ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-17T19:20:29.321279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.569177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-17T19:20:29.321314Z","caller":"traceutil/trace.go:171","msg":"trace[1297096011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2592; }","duration":"377.643598ms","start":"2026-09-17T19:20:28.943661Z","end":"2026-09-17T19:20:29.321305Z","steps":["trace[1297096011] 'agreement among raft nodes before linearized reading' (duration: 377.562957ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:29.321341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:28.94364Z","time spent":"377.696189ms","remote":"127.0.0.1:48788","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-17T19:20:43.87302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.181557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:20:43.873621Z","caller":"traceutil/trace.go:171","msg":"trace[642965439] transaction","detail":"{read_only:false; response_revision:2678; number_of_response:1; }","duration":"444.910147ms","start":"2026-09-17T19:20:43.428685Z","end":"2026-09-17T19:20:43.873595Z","steps":["trace[642965439] 'process raft request' (duration: 444.631733ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:43.87364Z","caller":"traceutil/trace.go:171","msg":"trace[681193742] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"444.857866ms","start":"2026-09-17T19:20:43.428756Z","end":"2026-09-17T19:20:43.873614Z","steps":["trace[681193742] 'process raft request' (duration: 444.724704ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:43.87369Z","caller":"traceutil/trace.go:171","msg":"trace[924274230] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"430.49513ms","start":"2026-09-17T19:20:43.443186Z","end":"2026-09-17T19:20:43.873682Z","steps":["trace[924274230] 'process raft request' (duration: 430.353748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:43.873765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:43.428672Z","time spent":"445.014309ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:20:43.873783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:43.428747Z","time spent":"444.959298ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:20:43.87363Z","caller":"traceutil/trace.go:171","msg":"trace[261933020] linearizableReadLoop","detail":"{readStateIndex:2784; appliedIndex:2778; }","duration":"425.085336ms","start":"2026-09-17T19:20:43.448521Z","end":"2026-09-17T19:20:43.873606Z","steps":["trace[261933020] 'read index received' (duration: 1.864587ms)","trace[261933020] 'applied index is now lower than readState.Index' (duration: 423.219229ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:20:43.873621Z","caller":"traceutil/trace.go:171","msg":"trace[1343024475] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"444.933858ms","start":"2026-09-17T19:20:43.428663Z","end":"2026-09-17T19:20:43.873597Z","steps":["trace[1343024475] 'process raft request' (duration: 324.077021ms)","trace[1343024475] 'compare' (duration: 119.980904ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:20:43.873967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:43.42864Z","time spent":"445.294892ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:20:43.873904Z","caller":"traceutil/trace.go:171","msg":"trace[348554885] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"445.08888ms","start":"2026-09-17T19:20:43.428751Z","end":"2026-09-17T19:20:43.87384Z","steps":["trace[348554885] 'process raft request' (duration: 444.685015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:43.874222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.698994ms","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-17T19:20:43.874336Z","caller":"traceutil/trace.go:171","msg":"trace[1797167332] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2681; }","duration":"425.847185ms","start":"2026-09-17T19:20:43.448474Z","end":"2026-09-17T19:20:43.874322Z","steps":["trace[1797167332] 'agreement among raft nodes before linearized reading' (duration: 425.191477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:20:43.874454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:43.448459Z","time spent":"425.981217ms","remote":"127.0.0.1:48980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-17T19:20:43.874226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:43.428744Z","time spent":"445.319193ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:20:43.87424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:20:43.44314Z","time spent":"430.578271ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:20:44.10083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.702806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:20:44.100939Z","caller":"traceutil/trace.go:171","msg":"trace[1356423092] transaction","detail":"{read_only:false; response_revision:2682; number_of_response:1; }","duration":"145.190549ms","start":"2026-09-17T19:20:43.955728Z","end":"2026-09-17T19:20:44.100918Z","steps":["trace[1356423092] 'process raft request' (duration: 23.31662ms)","trace[1356423092] 'compare' (duration: 121.292281ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:20:52.04641Z","caller":"traceutil/trace.go:171","msg":"trace[1595317421] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"112.411087ms","start":"2026-09-17T19:20:51.93397Z","end":"2026-09-17T19:20:52.046381Z","steps":["trace[1595317421] 'process raft request' (duration: 112.295035ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:52.046501Z","caller":"traceutil/trace.go:171","msg":"trace[1743853280] transaction","detail":"{read_only:false; response_revision:2723; number_of_response:1; }","duration":"112.393766ms","start":"2026-09-17T19:20:51.934078Z","end":"2026-09-17T19:20:52.046472Z","steps":["trace[1743853280] 'process raft request' (duration: 112.232294ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:52.046456Z","caller":"traceutil/trace.go:171","msg":"trace[1670611420] transaction","detail":"{read_only:false; response_revision:2721; number_of_response:1; }","duration":"113.536172ms","start":"2026-09-17T19:20:51.932879Z","end":"2026-09-17T19:20:52.046415Z","steps":["trace[1670611420] 'process raft request' (duration: 93.212226ms)","trace[1670611420] 'compare' (duration: 20.082943ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:20:55.638272Z","caller":"traceutil/trace.go:171","msg":"trace[1662207569] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"145.493819ms","start":"2026-09-17T19:20:55.492756Z","end":"2026-09-17T19:20:55.638249Z","steps":["trace[1662207569] 'process raft request' (duration: 145.342331ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:20:55.648684Z","caller":"traceutil/trace.go:171","msg":"trace[164171820] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"138.63735ms","start":"2026-09-17T19:20:55.510022Z","end":"2026-09-17T19:20:55.64866Z","steps":["trace[164171820] 'process raft request' (duration: 138.276751ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:21:07.1486Z","caller":"traceutil/trace.go:171","msg":"trace[367339241] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"103.851174ms","start":"2026-09-17T19:21:07.044734Z","end":"2026-09-17T19:21:07.148585Z","steps":["trace[367339241] 'process raft request' (duration: 103.739458ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:23:30.79929Z","caller":"traceutil/trace.go:171","msg":"trace[891752300] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3554; }","duration":"107.748443ms","start":"2026-09-17T19:23:30.691516Z","end":"2026-09-17T19:23:30.799264Z","steps":["trace[891752300] 'process raft request' (duration: 107.652341ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:23:30.799594Z","caller":"traceutil/trace.go:171","msg":"trace[1032633540] transaction","detail":"{read_only:false; response_revision:3554; number_of_response:1; }","duration":"110.836599ms","start":"2026-09-17T19:23:30.688722Z","end":"2026-09-17T19:23:30.799559Z","steps":["trace[1032633540] 'process raft request' (duration: 108.231786ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:24:13.132855Z","caller":"traceutil/trace.go:171","msg":"trace[747693171] transaction","detail":"{read_only:false; response_revision:3856; number_of_response:1; }","duration":"107.508845ms","start":"2026-09-17T19:24:13.025321Z","end":"2026-09-17T19:24:13.132829Z","steps":["trace[747693171] 'process raft request' (duration: 97.480314ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:24:41.689828Z","caller":"traceutil/trace.go:171","msg":"trace[488889736] transaction","detail":"{read_only:false; response_revision:4048; number_of_response:1; }","duration":"115.853587ms","start":"2026-09-17T19:24:41.57395Z","end":"2026-09-17T19:24:41.689804Z","steps":["trace[488889736] 'process raft request' (duration: 25.294753ms)","trace[488889736] 'compare' (duration: 90.189826ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:24:41.690173Z","caller":"traceutil/trace.go:171","msg":"trace[857398083] transaction","detail":"{read_only:false; response_revision:4049; number_of_response:1; }","duration":"112.15028ms","start":"2026-09-17T19:24:41.577995Z","end":"2026-09-17T19:24:41.690145Z","steps":["trace[857398083] 'process raft request' (duration: 111.666319ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:24:41.748476Z","caller":"traceutil/trace.go:171","msg":"trace[2050947473] transaction","detail":"{read_only:false; response_revision:4050; number_of_response:1; }","duration":"100.064517ms","start":"2026-09-17T19:24:41.648371Z","end":"2026-09-17T19:24:41.748436Z","steps":["trace[2050947473] 'process raft request' (duration: 99.801101ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:24:42.23414Z","caller":"traceutil/trace.go:171","msg":"trace[1958910158] transaction","detail":"{read_only:false; response_revision:4055; number_of_response:1; }","duration":"135.171003ms","start":"2026-09-17T19:24:42.098935Z","end":"2026-09-17T19:24:42.234106Z","steps":["trace[1958910158] 'process raft request' (duration: 85.890549ms)","trace[1958910158] 'compare' (duration: 49.123061ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:24:43.097528Z","caller":"traceutil/trace.go:171","msg":"trace[290843136] transaction","detail":"{read_only:false; response_revision:4069; number_of_response:1; }","duration":"113.220565ms","start":"2026-09-17T19:24:42.98429Z","end":"2026-09-17T19:24:43.097511Z","steps":["trace[290843136] 'process raft request' (duration: 30.083434ms)","trace[290843136] 'compare' (duration: 83.017698ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:24:43.113157Z","caller":"traceutil/trace.go:171","msg":"trace[335537309] transaction","detail":"{read_only:false; response_revision:4070; number_of_response:1; }","duration":"119.803058ms","start":"2026-09-17T19:24:42.993333Z","end":"2026-09-17T19:24:43.113136Z","steps":["trace[335537309] 'process raft request' (duration: 119.380938ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:24:43.264778Z","caller":"traceutil/trace.go:171","msg":"trace[1962714899] transaction","detail":"{read_only:false; response_revision:4072; number_of_response:1; }","duration":"125.790918ms","start":"2026-09-17T19:24:43.13896Z","end":"2026-09-17T19:24:43.264751Z","steps":["trace[1962714899] 'process raft request' (duration: 125.332177ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:24:43.270309Z","caller":"traceutil/trace.go:171","msg":"trace[262135337] transaction","detail":"{read_only:false; response_revision:4073; number_of_response:1; }","duration":"129.838542ms","start":"2026-09-17T19:24:43.140433Z","end":"2026-09-17T19:24:43.270271Z","steps":["trace[262135337] 'process raft request' (duration: 129.596867ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:24:43.270391Z","caller":"traceutil/trace.go:171","msg":"trace[461578421] transaction","detail":"{read_only:false; response_revision:4074; number_of_response:1; }","duration":"127.56987ms","start":"2026-09-17T19:24:43.142803Z","end":"2026-09-17T19:24:43.270373Z","steps":["trace[461578421] 'process raft request' (duration: 127.343135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:25:34.071719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.640217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:25:34.071828Z","caller":"traceutil/trace.go:171","msg":"trace[1768174087] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"176.001811ms","start":"2026-09-17T19:25:33.895807Z","end":"2026-09-17T19:25:34.071808Z","steps":["trace[1768174087] 'process raft request' (duration: 33.18317ms)","trace[1768174087] 'compare' (duration: 142.396282ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:25:34.155139Z","caller":"traceutil/trace.go:171","msg":"trace[397749522] linearizableReadLoop","detail":"{readStateIndex:4938; appliedIndex:4936; }","duration":"235.98266ms","start":"2026-09-17T19:25:33.919131Z","end":"2026-09-17T19:25:34.155113Z","steps":["trace[397749522] 'read index received' (duration: 9.936112ms)","trace[397749522] 'applied index is now lower than readState.Index' (duration: 226.045438ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:25:34.15597Z","caller":"traceutil/trace.go:171","msg":"trace[850669441] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"253.406473ms","start":"2026-09-17T19:25:33.902543Z","end":"2026-09-17T19:25:34.15595Z","steps":["trace[850669441] 'process raft request' (duration: 251.101343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:25:34.155967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.788907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-17T19:25:34.156126Z","caller":"traceutil/trace.go:171","msg":"trace[1700975910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4751; }","duration":"237.011901ms","start":"2026-09-17T19:25:33.919105Z","end":"2026-09-17T19:25:34.156117Z","steps":["trace[1700975910] 'agreement among raft nodes before linearized reading' (duration: 236.694325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:25:34.156167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.988122ms","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-17T19:25:34.156203Z","caller":"traceutil/trace.go:171","msg":"trace[1598823540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4751; }","duration":"194.061783ms","start":"2026-09-17T19:25:33.96213Z","end":"2026-09-17T19:25:34.156192Z","steps":["trace[1598823540] 'agreement among raft nodes before linearized reading' (duration: 193.981001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:25:34.156092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.861859ms","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-17T19:25:34.156286Z","caller":"traceutil/trace.go:171","msg":"trace[1178236721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4751; }","duration":"237.085673ms","start":"2026-09-17T19:25:33.919193Z","end":"2026-09-17T19:25:34.156279Z","steps":["trace[1178236721] 'agreement among raft nodes before linearized reading' (duration: 236.835758ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:25:38.873479Z","caller":"traceutil/trace.go:171","msg":"trace[248926911] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"105.908696ms","start":"2026-09-17T19:25:38.767529Z","end":"2026-09-17T19:25:38.873438Z","steps":["trace[248926911] 'process raft request' (duration: 105.462867ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:25:38.873826Z","caller":"traceutil/trace.go:171","msg":"trace[1971190707] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"105.899628ms","start":"2026-09-17T19:25:38.767919Z","end":"2026-09-17T19:25:38.873818Z","steps":["trace[1971190707] 'process raft request' (duration: 105.344215ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:25:38.873963Z","caller":"traceutil/trace.go:171","msg":"trace[1364743654] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"101.595186ms","start":"2026-09-17T19:25:38.772338Z","end":"2026-09-17T19:25:38.873933Z","steps":["trace[1364743654] 'process raft request' (duration: 100.973832ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:25:38.874113Z","caller":"traceutil/trace.go:171","msg":"trace[1795838310] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"101.535435ms","start":"2026-09-17T19:25:38.772541Z","end":"2026-09-17T19:25:38.874076Z","steps":["trace[1795838310] 'process raft request' (duration: 100.794599ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:25:38.874196Z","caller":"traceutil/trace.go:171","msg":"trace[638873059] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"106.148482ms","start":"2026-09-17T19:25:38.768039Z","end":"2026-09-17T19:25:38.874187Z","steps":["trace[638873059] 'process raft request' (duration: 105.253573ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:25:39.928658Z","caller":"traceutil/trace.go:171","msg":"trace[208774695] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"138.431043ms","start":"2026-09-17T19:25:39.790198Z","end":"2026-09-17T19:25:39.928629Z","steps":["trace[208774695] 'process raft request' (duration: 116.070309ms)","trace[208774695] 'compare' (duration: 22.208991ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:25:44.329251Z","caller":"traceutil/trace.go:171","msg":"trace[743868987] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"174.611194ms","start":"2026-09-17T19:25:44.154613Z","end":"2026-09-17T19:25:44.329224Z","steps":["trace[743868987] 'process raft request' (duration: 174.419771ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:25:44.471088Z","caller":"traceutil/trace.go:171","msg":"trace[1004888029] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"136.037062ms","start":"2026-09-17T19:25:44.335028Z","end":"2026-09-17T19:25:44.471065Z","steps":["trace[1004888029] 'process raft request' (duration: 123.937598ms)","trace[1004888029] 'compare' (duration: 12.015002ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:25:44.476776Z","caller":"traceutil/trace.go:171","msg":"trace[64452455] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"141.704341ms","start":"2026-09-17T19:25:44.335041Z","end":"2026-09-17T19:25:44.476746Z","steps":["trace[64452455] 'process raft request' (duration: 141.506327ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:25:44.476879Z","caller":"traceutil/trace.go:171","msg":"trace[689221406] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"141.722951ms","start":"2026-09-17T19:25:44.335137Z","end":"2026-09-17T19:25:44.47686Z","steps":["trace[689221406] 'process raft request' (duration: 141.561218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:26:00.438007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11906286721085805420,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-17T19:26:00.557714Z","caller":"traceutil/trace.go:171","msg":"trace[824974702] linearizableReadLoop","detail":"{readStateIndex:5399; appliedIndex:5398; }","duration":"620.177374ms","start":"2026-09-17T19:25:59.937513Z","end":"2026-09-17T19:26:00.55769Z","steps":["trace[824974702] 'read index received' (duration: 573.192676ms)","trace[824974702] 'applied index is now lower than readState.Index' (duration: 46.983658ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:26:00.557841Z","caller":"traceutil/trace.go:171","msg":"trace[614355695] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"708.478934ms","start":"2026-09-17T19:25:59.849349Z","end":"2026-09-17T19:26:00.557828Z","steps":["trace[614355695] 'process raft request' (duration: 661.373124ms)","trace[614355695] 'compare' (duration: 46.591569ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:26:00.557934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:25:59.849319Z","time spent":"708.545116ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:26:00.558396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.365727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-17T19:26:00.558493Z","caller":"traceutil/trace.go:171","msg":"trace[420238579] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5200; }","duration":"587.499959ms","start":"2026-09-17T19:25:59.970968Z","end":"2026-09-17T19:26:00.558468Z","steps":["trace[420238579] 'agreement among raft nodes before linearized reading' (duration: 587.04632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:26:00.558544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:25:59.970955Z","time spent":"587.575821ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-17T19:26:00.558545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.385468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:26:00.558591Z","caller":"traceutil/trace.go:171","msg":"trace[521965480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5200; }","duration":"321.468459ms","start":"2026-09-17T19:26:00.23711Z","end":"2026-09-17T19:26:00.558579Z","steps":["trace[521965480] 'agreement among raft nodes before linearized reading' (duration: 321.387438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:26:00.55862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:26:00.237092Z","time spent":"321.521061ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-17T19:26:00.558656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.123312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16550"} {"level":"info","ts":"2026-09-17T19:26:00.558747Z","caller":"traceutil/trace.go:171","msg":"trace[2097698157] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5200; }","duration":"621.259045ms","start":"2026-09-17T19:25:59.937463Z","end":"2026-09-17T19:26:00.558722Z","steps":["trace[2097698157] 'agreement among raft nodes before linearized reading' (duration: 620.968269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:26:00.558794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:25:59.937443Z","time spent":"621.340317ms","remote":"127.0.0.1:48732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16574,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-09-17T19:26:00.560234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.335192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-17T19:26:00.560321Z","caller":"traceutil/trace.go:171","msg":"trace[1539388900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5200; }","duration":"110.485585ms","start":"2026-09-17T19:26:00.449821Z","end":"2026-09-17T19:26:00.560307Z","steps":["trace[1539388900] 'agreement among raft nodes before linearized reading' (duration: 108.413433ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:26:00.692912Z","caller":"traceutil/trace.go:171","msg":"trace[376425150] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"113.523217ms","start":"2026-09-17T19:26:00.579365Z","end":"2026-09-17T19:26:00.692889Z","steps":["trace[376425150] 'process raft request' (duration: 108.935483ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:26:00.701358Z","caller":"traceutil/trace.go:171","msg":"trace[676173087] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"119.592832ms","start":"2026-09-17T19:26:00.58174Z","end":"2026-09-17T19:26:00.701333Z","steps":["trace[676173087] 'process raft request' (duration: 119.416048ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:26:03.179102Z","caller":"traceutil/trace.go:171","msg":"trace[10563453] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"138.319288ms","start":"2026-09-17T19:26:03.04076Z","end":"2026-09-17T19:26:03.17908Z","steps":["trace[10563453] 'process raft request' (duration: 114.704704ms)","trace[10563453] 'compare' (duration: 23.300318ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:26:03.179246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.239409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-17T19:26:03.179326Z","caller":"traceutil/trace.go:171","msg":"trace[1687479570] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5236; }","duration":"103.368851ms","start":"2026-09-17T19:26:03.075937Z","end":"2026-09-17T19:26:03.179306Z","steps":["trace[1687479570] 'agreement among raft nodes before linearized reading' (duration: 103.149846ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:26:03.179781Z","caller":"traceutil/trace.go:171","msg":"trace[383210903] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"137.159214ms","start":"2026-09-17T19:26:03.042611Z","end":"2026-09-17T19:26:03.17977Z","steps":["trace[383210903] 'process raft request' (duration: 136.345758ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:26:12.482828Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2832} {"level":"info","ts":"2026-09-17T19:26:12.561533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2832,"took":"77.421211ms","hash":3289967089,"current-db-size-bytes":52228096,"current-db-size":"52 MB","current-db-size-in-use-bytes":27553792,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-17T19:26:12.561608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3289967089,"revision":2832,"compact-revision":-1} {"level":"info","ts":"2026-09-17T19:26:44.042481Z","caller":"traceutil/trace.go:171","msg":"trace[879186329] transaction","detail":"{read_only:false; response_revision:5573; number_of_response:1; }","duration":"103.228587ms","start":"2026-09-17T19:26:43.939224Z","end":"2026-09-17T19:26:44.042452Z","steps":["trace[879186329] 'process raft request' (duration: 97.944553ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:27:37.551891Z","caller":"traceutil/trace.go:171","msg":"trace[1910015669] linearizableReadLoop","detail":"{readStateIndex:6182; appliedIndex:6180; }","duration":"102.591261ms","start":"2026-09-17T19:27:37.449278Z","end":"2026-09-17T19:27:37.551869Z","steps":["trace[1910015669] 'read index received' (duration: 57.211247ms)","trace[1910015669] 'applied index is now lower than readState.Index' (duration: 45.379134ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:27:37.552004Z","caller":"traceutil/trace.go:171","msg":"trace[2055605069] transaction","detail":"{read_only:false; response_revision:5960; number_of_response:1; }","duration":"121.14996ms","start":"2026-09-17T19:27:37.430799Z","end":"2026-09-17T19:27:37.551949Z","steps":["trace[2055605069] 'process raft request' (duration: 117.039683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:27:37.552178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.876537ms","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-17T19:27:37.552285Z","caller":"traceutil/trace.go:171","msg":"trace[242465949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5960; }","duration":"103.024899ms","start":"2026-09-17T19:27:37.44924Z","end":"2026-09-17T19:27:37.552265Z","steps":["trace[242465949] 'agreement among raft nodes before linearized reading' (duration: 102.746154ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:27:37.790051Z","caller":"traceutil/trace.go:171","msg":"trace[1547410392] transaction","detail":"{read_only:false; response_revision:5964; number_of_response:1; }","duration":"123.23895ms","start":"2026-09-17T19:27:37.666777Z","end":"2026-09-17T19:27:37.790016Z","steps":["trace[1547410392] 'process raft request' (duration: 99.403121ms)","trace[1547410392] 'compare' (duration: 23.512612ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:29:30.650391Z","caller":"traceutil/trace.go:171","msg":"trace[1763399063] transaction","detail":"{read_only:false; response_revision:6704; number_of_response:1; }","duration":"135.67723ms","start":"2026-09-17T19:29:30.514681Z","end":"2026-09-17T19:29:30.650358Z","steps":["trace[1763399063] 'process raft request' (duration: 85.202636ms)","trace[1763399063] 'compare' (duration: 49.930599ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:29:30.835057Z","caller":"traceutil/trace.go:171","msg":"trace[1764023671] transaction","detail":"{read_only:false; response_revision:6707; number_of_response:1; }","duration":"171.495865ms","start":"2026-09-17T19:29:30.663527Z","end":"2026-09-17T19:29:30.835023Z","steps":["trace[1764023671] 'process raft request' (duration: 171.396391ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:29:30.835367Z","caller":"traceutil/trace.go:171","msg":"trace[193565044] transaction","detail":"{read_only:false; response_revision:6706; number_of_response:1; }","duration":"178.581029ms","start":"2026-09-17T19:29:30.656649Z","end":"2026-09-17T19:29:30.83523Z","steps":["trace[193565044] 'process raft request' (duration: 119.590475ms)","trace[193565044] 'compare' (duration: 58.522572ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:29:31.018377Z","caller":"traceutil/trace.go:171","msg":"trace[2068632404] transaction","detail":"{read_only:false; response_revision:6708; number_of_response:1; }","duration":"172.796165ms","start":"2026-09-17T19:29:30.845556Z","end":"2026-09-17T19:29:31.018352Z","steps":["trace[2068632404] 'process raft request' (duration: 157.449105ms)","trace[2068632404] 'compare' (duration: 15.210014ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:29:46.838907Z","caller":"traceutil/trace.go:171","msg":"trace[460922728] transaction","detail":"{read_only:false; response_revision:6844; number_of_response:1; }","duration":"133.668712ms","start":"2026-09-17T19:29:46.705217Z","end":"2026-09-17T19:29:46.838886Z","steps":["trace[460922728] 'process raft request' (duration: 133.40732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:29:54.528212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.236232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T19:29:54.528802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.718241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:29:54.528986Z","caller":"traceutil/trace.go:171","msg":"trace[1320481174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6881; }","duration":"290.396159ms","start":"2026-09-17T19:29:54.237888Z","end":"2026-09-17T19:29:54.528284Z","steps":["trace[1320481174] 'range keys from in-memory index tree' (duration: 290.066054ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:29:54.529212Z","caller":"traceutil/trace.go:171","msg":"trace[546461709] transaction","detail":"{read_only:false; response_revision:6882; number_of_response:1; }","duration":"290.600478ms","start":"2026-09-17T19:29:54.238329Z","end":"2026-09-17T19:29:54.528929Z","steps":["trace[546461709] 'process raft request' (duration: 109.648357ms)","trace[546461709] 'compare' (duration: 180.238089ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:29:54.603016Z","caller":"traceutil/trace.go:171","msg":"trace[1152344902] transaction","detail":"{read_only:false; response_revision:6883; number_of_response:1; }","duration":"300.308042ms","start":"2026-09-17T19:29:54.302682Z","end":"2026-09-17T19:29:54.60299Z","steps":["trace[1152344902] 'process raft request' (duration: 300.04644ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:29:54.603204Z","caller":"traceutil/trace.go:171","msg":"trace[678053184] linearizableReadLoop","detail":"{readStateIndex:7137; appliedIndex:7136; }","duration":"121.80636ms","start":"2026-09-17T19:29:54.48109Z","end":"2026-09-17T19:29:54.602896Z","steps":["trace[678053184] 'read index received' (duration: 121.700306ms)","trace[678053184] 'applied index is now lower than readState.Index' (duration: 104.964µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:29:54.603239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.128835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-17T19:29:54.603293Z","caller":"traceutil/trace.go:171","msg":"trace[662600604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6883; }","duration":"122.2409ms","start":"2026-09-17T19:29:54.481037Z","end":"2026-09-17T19:29:54.603278Z","steps":["trace[662600604] 'agreement among raft nodes before linearized reading' (duration: 122.057751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:29:54.60355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:29:54.302649Z","time spent":"300.809874ms","remote":"127.0.0.1:48788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:30:00.80508Z","caller":"traceutil/trace.go:171","msg":"trace[1794446490] transaction","detail":"{read_only:false; response_revision:6914; number_of_response:1; }","duration":"125.053754ms","start":"2026-09-17T19:30:00.679986Z","end":"2026-09-17T19:30:00.80504Z","steps":["trace[1794446490] 'process raft request' (duration: 61.844657ms)","trace[1794446490] 'compare' (duration: 62.941526ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:30:03.854738Z","caller":"traceutil/trace.go:171","msg":"trace[1878996935] transaction","detail":"{read_only:false; response_revision:6936; number_of_response:1; }","duration":"101.374962ms","start":"2026-09-17T19:30:03.753336Z","end":"2026-09-17T19:30:03.85471Z","steps":["trace[1878996935] 'process raft request' (duration: 101.09092ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:30:45.027865Z","caller":"traceutil/trace.go:171","msg":"trace[642673538] transaction","detail":"{read_only:false; response_revision:7195; number_of_response:1; }","duration":"174.697323ms","start":"2026-09-17T19:30:44.853143Z","end":"2026-09-17T19:30:45.02784Z","steps":["trace[642673538] 'process raft request' (duration: 108.190888ms)","trace[642673538] 'compare' (duration: 66.394421ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:30:45.120268Z","caller":"traceutil/trace.go:171","msg":"trace[955692714] transaction","detail":"{read_only:false; response_revision:7197; number_of_response:1; }","duration":"107.660249ms","start":"2026-09-17T19:30:45.01254Z","end":"2026-09-17T19:30:45.1202Z","steps":["trace[955692714] 'process raft request' (duration: 107.555355ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:30:47.186413Z","caller":"traceutil/trace.go:171","msg":"trace[450723] transaction","detail":"{read_only:false; response_revision:7211; number_of_response:1; }","duration":"121.176586ms","start":"2026-09-17T19:30:47.065208Z","end":"2026-09-17T19:30:47.186384Z","steps":["trace[450723] 'process raft request' (duration: 120.990708ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:31:12.491331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5290} {"level":"info","ts":"2026-09-17T19:31:12.585315Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5290,"took":"92.204874ms","hash":1286451597,"current-db-size-bytes":52228096,"current-db-size":"52 MB","current-db-size-in-use-bytes":18776064,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-17T19:31:12.585376Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1286451597,"revision":5290,"compact-revision":2832}