{"level":"warn","ts":"2026-05-20T21:20:51.22844Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T21:20:51.228561Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-05-20T21:20:51.22867Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T21:20:51.22869Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-20T21:20:51.228723Z","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-05-20T21:20:51.230026Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-05-20T21:20:51.230164Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-05-20T21:20:51.243604Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.256799ms"} {"level":"info","ts":"2026-05-20T21:20:51.268278Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-05-20T21:20:51.268482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T21:20:51.268513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-05-20T21:20:51.268589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T21:20:51.268726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-05-20T21:20:51.268772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-05-20T21:20:51.278938Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T21:20:51.280229Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T21:20:51.281436Z","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-05-20T21:20:51.283562Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T21:20:51.284007Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T21:20:51.284066Z","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-05-20T21:20:51.284157Z","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-05-20T21:20:51.284171Z","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-05-20T21:20:51.289363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-05-20T21:20:51.291096Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-20T21:20:51.292527Z","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-05-20T21:20:51.292765Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-20T21:20:51.292814Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-20T21:20:51.293047Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T21:20:51.293113Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T21:20:51.669522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T21:20:51.669576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T21:20:51.669598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-05-20T21:20:51.669617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-05-20T21:20:51.669632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-20T21:20:51.669651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-05-20T21:20:51.669668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-20T21:20:51.670781Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T21:20:51.671761Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T21:20:51.671766Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T21:20:51.67182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T21:20:51.672151Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T21:20:51.672224Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T21:20:51.672423Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T21:20:51.672584Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T21:20:51.672637Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T21:20:51.675099Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-05-20T21:20:51.676222Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T21:21:02.123759Z","caller":"traceutil/trace.go:171","msg":"trace[1054606628] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"290.373745ms","start":"2026-05-20T21:21:01.833366Z","end":"2026-05-20T21:21:02.12374Z","steps":["trace[1054606628] 'process raft request' (duration: 271.356011ms)","trace[1054606628] 'compare' (duration: 18.934352ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:02.15335Z","caller":"traceutil/trace.go:171","msg":"trace[404619546] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:375; }","duration":"315.963957ms","start":"2026-05-20T21:21:01.83736Z","end":"2026-05-20T21:21:02.153324Z","steps":["trace[404619546] 'read index received' (duration: 267.358576ms)","trace[404619546] 'applied index is now lower than readState.Index' (duration: 48.604321ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:02.153589Z","caller":"traceutil/trace.go:171","msg":"trace[1775917274] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"319.41088ms","start":"2026-05-20T21:21:01.834158Z","end":"2026-05-20T21:21:02.153569Z","steps":["trace[1775917274] 'process raft request' (duration: 319.018012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:02.153642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.244493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:21:02.153682Z","caller":"traceutil/trace.go:171","msg":"trace[1722656656] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"317.97562ms","start":"2026-05-20T21:21:01.835674Z","end":"2026-05-20T21:21:02.153649Z","steps":["trace[1722656656] 'process raft request' (duration: 317.604372ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:02.153723Z","caller":"traceutil/trace.go:171","msg":"trace[483029197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:365; }","duration":"316.374335ms","start":"2026-05-20T21:21:01.837332Z","end":"2026-05-20T21:21:02.153707Z","steps":["trace[483029197] 'agreement among raft nodes before linearized reading' (duration: 316.199732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:02.154095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:01.837322Z","time spent":"316.429986ms","remote":"127.0.0.1:49898","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-05-20T21:21:02.154449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.380949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-20T21:21:02.154835Z","caller":"traceutil/trace.go:171","msg":"trace[1443886185] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:365; }","duration":"279.332719ms","start":"2026-05-20T21:21:01.875304Z","end":"2026-05-20T21:21:02.154637Z","steps":["trace[1443886185] 'agreement among raft nodes before linearized reading' (duration: 278.330498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:02.155026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:01.834129Z","time spent":"319.513113ms","remote":"127.0.0.1:50246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:21:02.154823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.157689ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:21:02.155278Z","caller":"traceutil/trace.go:171","msg":"trace[1023273140] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:365; }","duration":"155.562258ms","start":"2026-05-20T21:21:01.999637Z","end":"2026-05-20T21:21:02.155199Z","steps":["trace[1023273140] 'agreement among raft nodes before linearized reading' (duration: 154.123408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:02.155397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:01.83566Z","time spent":"318.075392ms","remote":"127.0.0.1:50246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:21:02.154922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.55945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-20T21:21:02.158861Z","caller":"traceutil/trace.go:171","msg":"trace[1415492531] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:365; }","duration":"233.467742ms","start":"2026-05-20T21:21:01.925309Z","end":"2026-05-20T21:21:02.158776Z","steps":["trace[1415492531] 'agreement among raft nodes before linearized reading' (duration: 229.11322ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:02.392565Z","caller":"traceutil/trace.go:171","msg":"trace[154127554] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"228.75821ms","start":"2026-05-20T21:21:02.163783Z","end":"2026-05-20T21:21:02.392541Z","steps":["trace[154127554] 'process raft request' (duration: 228.587425ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:02.393032Z","caller":"traceutil/trace.go:171","msg":"trace[1089654520] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:378; }","duration":"229.161977ms","start":"2026-05-20T21:21:02.163857Z","end":"2026-05-20T21:21:02.393019Z","steps":["trace[1089654520] 'read index received' (duration: 44.310239ms)","trace[1089654520] 'applied index is now lower than readState.Index' (duration: 184.851158ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:02.393027Z","caller":"traceutil/trace.go:171","msg":"trace[1246640945] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"229.132587ms","start":"2026-05-20T21:21:02.163865Z","end":"2026-05-20T21:21:02.392997Z","steps":["trace[1246640945] 'process raft request' (duration: 228.631037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:02.393473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.538817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:2205"} {"level":"info","ts":"2026-05-20T21:21:02.393558Z","caller":"traceutil/trace.go:171","msg":"trace[1444340917] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:369; }","duration":"229.647229ms","start":"2026-05-20T21:21:02.163901Z","end":"2026-05-20T21:21:02.393548Z","steps":["trace[1444340917] 'agreement among raft nodes before linearized reading' (duration: 229.544016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:02.393965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.125733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:21:02.394076Z","caller":"traceutil/trace.go:171","msg":"trace[1206508373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:369; }","duration":"101.240366ms","start":"2026-05-20T21:21:02.292779Z","end":"2026-05-20T21:21:02.394019Z","steps":["trace[1206508373] 'agreement among raft nodes before linearized reading' (duration: 100.99449ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:02.393079Z","caller":"traceutil/trace.go:171","msg":"trace[2143572838] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"227.064425ms","start":"2026-05-20T21:21:02.165991Z","end":"2026-05-20T21:21:02.393055Z","steps":["trace[2143572838] 'process raft request' (duration: 226.623655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:02.393214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.343461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-20T21:21:02.394559Z","caller":"traceutil/trace.go:171","msg":"trace[494757967] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:369; }","duration":"230.727412ms","start":"2026-05-20T21:21:02.163821Z","end":"2026-05-20T21:21:02.394549Z","steps":["trace[494757967] 'agreement among raft nodes before linearized reading' (duration: 229.287151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:02.393853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.983918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-05-20T21:21:02.394733Z","caller":"traceutil/trace.go:171","msg":"trace[1534712029] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:369; }","duration":"182.908617ms","start":"2026-05-20T21:21:02.211817Z","end":"2026-05-20T21:21:02.394725Z","steps":["trace[1534712029] 'agreement among raft nodes before linearized reading' (duration: 181.966377ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:11.14492Z","caller":"traceutil/trace.go:171","msg":"trace[1812602551] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"454.352635ms","start":"2026-05-20T21:21:10.690541Z","end":"2026-05-20T21:21:11.144893Z","steps":["trace[1812602551] 'process raft request' (duration: 454.184131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:11.145078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:10.690521Z","time spent":"454.478668ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:21:11.800213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.189508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T21:21:11.800349Z","caller":"traceutil/trace.go:171","msg":"trace[1537389971] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"505.339207ms","start":"2026-05-20T21:21:11.294991Z","end":"2026-05-20T21:21:11.80033Z","steps":["trace[1537389971] 'read index received' (duration: 115.76286ms)","trace[1537389971] 'applied index is now lower than readState.Index' (duration: 389.574677ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:11.800421Z","caller":"traceutil/trace.go:171","msg":"trace[259631786] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"612.504197ms","start":"2026-05-20T21:21:11.18787Z","end":"2026-05-20T21:21:11.800374Z","steps":["trace[259631786] 'process raft request' (duration: 222.88535ms)","trace[259631786] 'compare' (duration: 389.062656ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:21:11.80053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.172276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-20T21:21:11.80062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:11.187851Z","time spent":"612.670561ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:21:11.800627Z","caller":"traceutil/trace.go:171","msg":"trace[1244225383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"360.305549ms","start":"2026-05-20T21:21:11.440299Z","end":"2026-05-20T21:21:11.800605Z","steps":["trace[1244225383] 'agreement among raft nodes before linearized reading' (duration: 360.156986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:11.800683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:11.440213Z","time spent":"360.454193ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T21:21:11.800463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.477069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:21:11.800838Z","caller":"traceutil/trace.go:171","msg":"trace[1829962952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:424; }","duration":"505.895538ms","start":"2026-05-20T21:21:11.294932Z","end":"2026-05-20T21:21:11.800827Z","steps":["trace[1829962952] 'agreement among raft nodes before linearized reading' (duration: 505.49425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:11.800874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:11.29491Z","time spent":"505.955229ms","remote":"127.0.0.1:49898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-20T21:21:12.10009Z","caller":"traceutil/trace.go:171","msg":"trace[479953994] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"294.787096ms","start":"2026-05-20T21:21:11.805279Z","end":"2026-05-20T21:21:12.100066Z","steps":["trace[479953994] 'process raft request' (duration: 274.321752ms)","trace[479953994] 'compare' (duration: 20.372232ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:21:14.955208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.513287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T21:21:14.955431Z","caller":"traceutil/trace.go:171","msg":"trace[330552940] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"190.733277ms","start":"2026-05-20T21:21:14.76468Z","end":"2026-05-20T21:21:14.955413Z","steps":["trace[330552940] 'process raft request' (duration: 190.619095ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:14.95558Z","caller":"traceutil/trace.go:171","msg":"trace[375112623] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"194.892222ms","start":"2026-05-20T21:21:14.76055Z","end":"2026-05-20T21:21:14.955442Z","steps":["trace[375112623] 'process raft request' (duration: 82.088389ms)","trace[375112623] 'compare' (duration: 112.400765ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:14.963217Z","caller":"traceutil/trace.go:171","msg":"trace[804156582] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"190.512732ms","start":"2026-05-20T21:21:14.772682Z","end":"2026-05-20T21:21:14.963195Z","steps":["trace[804156582] 'process raft request' (duration: 190.306148ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:14.963326Z","caller":"traceutil/trace.go:171","msg":"trace[1085279387] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"189.243548ms","start":"2026-05-20T21:21:14.774061Z","end":"2026-05-20T21:21:14.963304Z","steps":["trace[1085279387] 'process raft request' (duration: 189.186276ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:14.963436Z","caller":"traceutil/trace.go:171","msg":"trace[780597417] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"190.642836ms","start":"2026-05-20T21:21:14.772769Z","end":"2026-05-20T21:21:14.963412Z","steps":["trace[780597417] 'process raft request' (duration: 190.334049ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:14.963561Z","caller":"traceutil/trace.go:171","msg":"trace[203615396] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"189.470312ms","start":"2026-05-20T21:21:14.774048Z","end":"2026-05-20T21:21:14.963518Z","steps":["trace[203615396] 'process raft request' (duration: 189.091164ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:15.166008Z","caller":"traceutil/trace.go:171","msg":"trace[1464660475] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"109.442418ms","start":"2026-05-20T21:21:15.056513Z","end":"2026-05-20T21:21:15.165955Z","steps":["trace[1464660475] 'process raft request' (duration: 109.375247ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:15.166079Z","caller":"traceutil/trace.go:171","msg":"trace[1797533749] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"111.293226ms","start":"2026-05-20T21:21:15.054773Z","end":"2026-05-20T21:21:15.166066Z","steps":["trace[1797533749] 'read index received' (duration: 42.418132ms)","trace[1797533749] 'applied index is now lower than readState.Index' (duration: 68.872844ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:15.165988Z","caller":"traceutil/trace.go:171","msg":"trace[1186113642] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"195.185961ms","start":"2026-05-20T21:21:14.970768Z","end":"2026-05-20T21:21:15.165954Z","steps":["trace[1186113642] 'process raft request' (duration: 126.407169ms)","trace[1186113642] 'compare' (duration: 68.556568ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:21:15.166313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.518691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-4qnk2\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-05-20T21:21:15.166411Z","caller":"traceutil/trace.go:171","msg":"trace[1393440324] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-4qnk2; range_end:; response_count:1; response_revision:445; }","duration":"111.658073ms","start":"2026-05-20T21:21:15.054734Z","end":"2026-05-20T21:21:15.166392Z","steps":["trace[1393440324] 'agreement among raft nodes before linearized reading' (duration: 111.48803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:15.16654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.504549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-05-20T21:21:15.166601Z","caller":"traceutil/trace.go:171","msg":"trace[2127844854] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:445; }","duration":"109.584651ms","start":"2026-05-20T21:21:15.057001Z","end":"2026-05-20T21:21:15.166585Z","steps":["trace[2127844854] 'agreement among raft nodes before linearized reading' (duration: 109.486749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:15.468543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.752325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-20T21:21:15.468704Z","caller":"traceutil/trace.go:171","msg":"trace[281839535] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"260.695855ms","start":"2026-05-20T21:21:15.207992Z","end":"2026-05-20T21:21:15.468688Z","steps":["trace[281839535] 'process raft request' (duration: 260.636754ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:15.468882Z","caller":"traceutil/trace.go:171","msg":"trace[426253402] transaction","detail":"{read_only:false; number_of_response:1; response_revision:449; }","duration":"263.265769ms","start":"2026-05-20T21:21:15.205579Z","end":"2026-05-20T21:21:15.468845Z","steps":["trace[426253402] 'process raft request' (duration: 117.083705ms)","trace[426253402] 'compare' (duration: 145.627103ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:15.475125Z","caller":"traceutil/trace.go:171","msg":"trace[1092605832] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"214.296802ms","start":"2026-05-20T21:21:15.260813Z","end":"2026-05-20T21:21:15.475109Z","steps":["trace[1092605832] 'process raft request' (duration: 214.114208ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:15.475155Z","caller":"traceutil/trace.go:171","msg":"trace[1240066234] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"210.545346ms","start":"2026-05-20T21:21:15.264589Z","end":"2026-05-20T21:21:15.475134Z","steps":["trace[1240066234] 'process raft request' (duration: 210.457694ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:15.475436Z","caller":"traceutil/trace.go:171","msg":"trace[457242026] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"213.324883ms","start":"2026-05-20T21:21:15.262071Z","end":"2026-05-20T21:21:15.475395Z","steps":["trace[457242026] 'process raft request' (duration: 212.936744ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:15.773666Z","caller":"traceutil/trace.go:171","msg":"trace[1267055051] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"215.066028ms","start":"2026-05-20T21:21:15.558579Z","end":"2026-05-20T21:21:15.773645Z","steps":["trace[1267055051] 'process raft request' (duration: 159.464586ms)","trace[1267055051] 'compare' (duration: 55.52371ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:15.779233Z","caller":"traceutil/trace.go:171","msg":"trace[406274504] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"220.102322ms","start":"2026-05-20T21:21:15.559117Z","end":"2026-05-20T21:21:15.779219Z","steps":["trace[406274504] 'process raft request' (duration: 220.0435ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:28.017781Z","caller":"traceutil/trace.go:171","msg":"trace[213186464] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"326.409292ms","start":"2026-05-20T21:21:27.691323Z","end":"2026-05-20T21:21:28.017733Z","steps":["trace[213186464] 'process raft request' (duration: 326.196608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:28.017935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:27.691306Z","time spent":"326.560576ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T21:21:28.039254Z","caller":"traceutil/trace.go:171","msg":"trace[1251577976] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:546; }","duration":"139.73809ms","start":"2026-05-20T21:21:27.899502Z","end":"2026-05-20T21:21:28.03924Z","steps":["trace[1251577976] 'read index received' (duration: 118.050346ms)","trace[1251577976] 'applied index is now lower than readState.Index' (duration: 21.687094ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:21:28.039353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.833851ms","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-05-20T21:21:28.039372Z","caller":"traceutil/trace.go:171","msg":"trace[1096562800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"139.909503ms","start":"2026-05-20T21:21:27.899457Z","end":"2026-05-20T21:21:28.039367Z","steps":["trace[1096562800] 'agreement among raft nodes before linearized reading' (duration: 139.836762ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:28.039592Z","caller":"traceutil/trace.go:171","msg":"trace[1362341256] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"348.081705ms","start":"2026-05-20T21:21:27.691504Z","end":"2026-05-20T21:21:28.039586Z","steps":["trace[1362341256] 'process raft request' (duration: 347.087636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:28.039636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:27.691498Z","time spent":"348.109905ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T21:21:28.0397Z","caller":"traceutil/trace.go:171","msg":"trace[1026438912] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"347.854721ms","start":"2026-05-20T21:21:27.691841Z","end":"2026-05-20T21:21:28.039696Z","steps":["trace[1026438912] 'process raft request' (duration: 346.817121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:28.039725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:27.691824Z","time spent":"347.890422ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T21:21:28.039776Z","caller":"traceutil/trace.go:171","msg":"trace[1737857495] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"337.045095ms","start":"2026-05-20T21:21:27.702727Z","end":"2026-05-20T21:21:28.039772Z","steps":["trace[1737857495] 'process raft request' (duration: 335.953034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:28.039799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:27.702701Z","time spent":"337.088495ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T21:21:28.039853Z","caller":"traceutil/trace.go:171","msg":"trace[122573302] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"336.2981ms","start":"2026-05-20T21:21:27.703551Z","end":"2026-05-20T21:21:28.039849Z","steps":["trace[122573302] 'process raft request' (duration: 335.150047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:28.039875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:27.703535Z","time spent":"336.33151ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T21:21:28.039927Z","caller":"traceutil/trace.go:171","msg":"trace[836426597] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"329.234208ms","start":"2026-05-20T21:21:27.710689Z","end":"2026-05-20T21:21:28.039924Z","steps":["trace[836426597] 'process raft request' (duration: 328.029714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:28.039949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:27.71067Z","time spent":"329.269949ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T21:21:28.039999Z","caller":"traceutil/trace.go:171","msg":"trace[1054574530] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"329.254038ms","start":"2026-05-20T21:21:27.710742Z","end":"2026-05-20T21:21:28.039996Z","steps":["trace[1054574530] 'process raft request' (duration: 328.031504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:28.040022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:27.710738Z","time spent":"329.275108ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T21:21:28.040071Z","caller":"traceutil/trace.go:171","msg":"trace[1264120396] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"327.37518ms","start":"2026-05-20T21:21:27.712692Z","end":"2026-05-20T21:21:28.040068Z","steps":["trace[1264120396] 'process raft request' (duration: 326.100435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:28.040092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:27.712682Z","time spent":"327.402152ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T21:21:28.040168Z","caller":"traceutil/trace.go:171","msg":"trace[312475955] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"327.316379ms","start":"2026-05-20T21:21:27.712822Z","end":"2026-05-20T21:21:28.040138Z","steps":["trace[312475955] 'process raft request' (duration: 325.997223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:28.040191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:27.712817Z","time spent":"327.364831ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T21:21:28.040242Z","caller":"traceutil/trace.go:171","msg":"trace[19272134] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"321.986213ms","start":"2026-05-20T21:21:27.718252Z","end":"2026-05-20T21:21:28.040238Z","steps":["trace[19272134] 'process raft request' (duration: 320.594775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:28.040264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:27.718246Z","time spent":"322.009743ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T21:21:28.040317Z","caller":"traceutil/trace.go:171","msg":"trace[471415939] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"307.064453ms","start":"2026-05-20T21:21:27.733249Z","end":"2026-05-20T21:21:28.040314Z","steps":["trace[471415939] 'process raft request' (duration: 305.627645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:28.040339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:27.733223Z","time spent":"307.106275ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T21:21:28.040389Z","caller":"traceutil/trace.go:171","msg":"trace[1382135240] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"213.879826ms","start":"2026-05-20T21:21:27.826505Z","end":"2026-05-20T21:21:28.040385Z","steps":["trace[1382135240] 'process raft request' (duration: 212.455437ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:32.151244Z","caller":"traceutil/trace.go:171","msg":"trace[761617396] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"118.352641ms","start":"2026-05-20T21:21:32.032865Z","end":"2026-05-20T21:21:32.151218Z","steps":["trace[761617396] 'read index received' (duration: 117.546315ms)","trace[761617396] 'applied index is now lower than readState.Index' (duration: 804.796µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:32.152133Z","caller":"traceutil/trace.go:171","msg":"trace[1759014075] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"127.459791ms","start":"2026-05-20T21:21:32.024659Z","end":"2026-05-20T21:21:32.152119Z","steps":["trace[1759014075] 'process raft request' (duration: 125.967263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:32.152446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.446273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:21:32.152496Z","caller":"traceutil/trace.go:171","msg":"trace[701160555] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:617; }","duration":"119.517224ms","start":"2026-05-20T21:21:32.032968Z","end":"2026-05-20T21:21:32.152486Z","steps":["trace[701160555] 'agreement among raft nodes before linearized reading' (duration: 119.438552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:32.154021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.636306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:21:32.154075Z","caller":"traceutil/trace.go:171","msg":"trace[1390859774] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:617; }","duration":"120.707398ms","start":"2026-05-20T21:21:32.033357Z","end":"2026-05-20T21:21:32.154064Z","steps":["trace[1390859774] 'agreement among raft nodes before linearized reading' (duration: 120.626676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:32.164584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.436672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:21:32.164656Z","caller":"traceutil/trace.go:171","msg":"trace[329540960] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:617; }","duration":"131.791798ms","start":"2026-05-20T21:21:32.032853Z","end":"2026-05-20T21:21:32.164644Z","steps":["trace[329540960] 'agreement among raft nodes before linearized reading' (duration: 119.425122ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:34.519927Z","caller":"traceutil/trace.go:171","msg":"trace[393055624] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"293.667603ms","start":"2026-05-20T21:21:34.226208Z","end":"2026-05-20T21:21:34.519876Z","steps":["trace[393055624] 'read index received' (duration: 292.485059ms)","trace[393055624] 'applied index is now lower than readState.Index' (duration: 1.181514ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:34.519992Z","caller":"traceutil/trace.go:171","msg":"trace[637234518] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"378.639016ms","start":"2026-05-20T21:21:34.141317Z","end":"2026-05-20T21:21:34.519956Z","steps":["trace[637234518] 'process raft request' (duration: 377.762268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:34.520287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.057732ms","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-05-20T21:21:34.520412Z","caller":"traceutil/trace.go:171","msg":"trace[369525152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"294.222005ms","start":"2026-05-20T21:21:34.226147Z","end":"2026-05-20T21:21:34.520369Z","steps":["trace[369525152] 'agreement among raft nodes before linearized reading' (duration: 293.862868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:34.520527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.892683ms","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-05-20T21:21:34.520622Z","caller":"traceutil/trace.go:171","msg":"trace[1977793282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"216.036016ms","start":"2026-05-20T21:21:34.304566Z","end":"2026-05-20T21:21:34.520602Z","steps":["trace[1977793282] 'agreement among raft nodes before linearized reading' (duration: 215.771642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:34.520855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.652747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T21:21:34.520855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:34.141289Z","time spent":"379.005272ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:21:34.520922Z","caller":"traceutil/trace.go:171","msg":"trace[137866980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"224.720119ms","start":"2026-05-20T21:21:34.296191Z","end":"2026-05-20T21:21:34.520911Z","steps":["trace[137866980] 'agreement among raft nodes before linearized reading' (duration: 224.097276ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:34.766427Z","caller":"traceutil/trace.go:171","msg":"trace[1123396388] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"231.557504ms","start":"2026-05-20T21:21:34.53484Z","end":"2026-05-20T21:21:34.766397Z","steps":["trace[1123396388] 'process raft request' (duration: 156.198503ms)","trace[1123396388] 'compare' (duration: 75.158798ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:34.808445Z","caller":"traceutil/trace.go:171","msg":"trace[1565592058] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:657; }","duration":"176.516515ms","start":"2026-05-20T21:21:34.631909Z","end":"2026-05-20T21:21:34.808425Z","steps":["trace[1565592058] 'read index received' (duration: 59.139671ms)","trace[1565592058] 'applied index is now lower than readState.Index' (duration: 117.376094ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:34.808538Z","caller":"traceutil/trace.go:171","msg":"trace[470789828] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"208.418077ms","start":"2026-05-20T21:21:34.600112Z","end":"2026-05-20T21:21:34.80853Z","steps":["trace[470789828] 'process raft request' (duration: 208.283334ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:34.808692Z","caller":"traceutil/trace.go:171","msg":"trace[363227881] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"269.915474ms","start":"2026-05-20T21:21:34.538613Z","end":"2026-05-20T21:21:34.808528Z","steps":["trace[363227881] 'process raft request' (duration: 269.667039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:34.808881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.239623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ck8mq\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-20T21:21:34.80886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.912563ms","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-05-20T21:21:34.808962Z","caller":"traceutil/trace.go:171","msg":"trace[1008901934] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ck8mq; range_end:; response_count:1; response_revision:636; }","duration":"109.459266ms","start":"2026-05-20T21:21:34.699467Z","end":"2026-05-20T21:21:34.808927Z","steps":["trace[1008901934] 'agreement among raft nodes before linearized reading' (duration: 109.10232ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:34.808989Z","caller":"traceutil/trace.go:171","msg":"trace[1512683864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"177.120207ms","start":"2026-05-20T21:21:34.631829Z","end":"2026-05-20T21:21:34.808949Z","steps":["trace[1512683864] 'agreement among raft nodes before linearized reading' (duration: 176.79487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:35.875232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.244813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T21:21:35.875409Z","caller":"traceutil/trace.go:171","msg":"trace[162940896] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.051595549s","start":"2026-05-20T21:21:34.823798Z","end":"2026-05-20T21:21:35.875394Z","steps":["trace[162940896] 'process raft request' (duration: 1.051564339s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:35.875461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:34.823777Z","time spent":"1.051662901s","remote":"127.0.0.1:49924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:21:35.875601Z","caller":"traceutil/trace.go:171","msg":"trace[184857244] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"1.055080177s","start":"2026-05-20T21:21:34.820498Z","end":"2026-05-20T21:21:35.875578Z","steps":["trace[184857244] 'read index received' (duration: 2.776055ms)","trace[184857244] 'applied index is now lower than readState.Index' (duration: 1.052301022s)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:35.875624Z","caller":"traceutil/trace.go:171","msg":"trace[829642222] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.052720681s","start":"2026-05-20T21:21:34.822859Z","end":"2026-05-20T21:21:35.87558Z","steps":["trace[829642222] 'process raft request' (duration: 1.052456065s)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:35.875672Z","caller":"traceutil/trace.go:171","msg":"trace[811473468] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.059234458s","start":"2026-05-20T21:21:34.81639Z","end":"2026-05-20T21:21:35.875624Z","steps":["trace[811473468] 'process raft request' (duration: 500.534907ms)","trace[811473468] 'compare' (duration: 557.993348ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:21:35.875753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05524356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T21:21:35.875771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.204097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:21:35.875787Z","caller":"traceutil/trace.go:171","msg":"trace[1197214425] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:639; }","duration":"1.055332933s","start":"2026-05-20T21:21:34.820441Z","end":"2026-05-20T21:21:35.875774Z","steps":["trace[1197214425] 'agreement among raft nodes before linearized reading' (duration: 1.055251661s)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:35.875799Z","caller":"traceutil/trace.go:171","msg":"trace[1980473074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"580.311239ms","start":"2026-05-20T21:21:35.29548Z","end":"2026-05-20T21:21:35.875791Z","steps":["trace[1980473074] 'agreement among raft nodes before linearized reading' (duration: 580.261798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:35.875821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:35.295444Z","time spent":"580.3727ms","remote":"127.0.0.1:49898","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-05-20T21:21:35.875824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:34.815719Z","time spent":"1.060029325s","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:21:35.875828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:34.820417Z","time spent":"1.055399424s","remote":"127.0.0.1:50146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-20T21:21:35.875828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:34.822838Z","time spent":"1.052877433s","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:21:35.875881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.919664ms","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-05-20T21:21:35.875918Z","caller":"traceutil/trace.go:171","msg":"trace[951306956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"443.003765ms","start":"2026-05-20T21:21:35.432904Z","end":"2026-05-20T21:21:35.875908Z","steps":["trace[951306956] 'agreement among raft nodes before linearized reading' (duration: 442.878843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:35.875942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:35.432874Z","time spent":"443.061666ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-20T21:21:36.968771Z","caller":"traceutil/trace.go:171","msg":"trace[409801001] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"152.158752ms","start":"2026-05-20T21:21:36.816585Z","end":"2026-05-20T21:21:36.968744Z","steps":["trace[409801001] 'process raft request' (duration: 152.009729ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:21:52.11625Z","caller":"traceutil/trace.go:171","msg":"trace[1176675165] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"159.127478ms","start":"2026-05-20T21:21:51.9571Z","end":"2026-05-20T21:21:52.116227Z","steps":["trace[1176675165] 'read index received' (duration: 158.942684ms)","trace[1176675165] 'applied index is now lower than readState.Index' (duration: 183.814µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:21:52.116292Z","caller":"traceutil/trace.go:171","msg":"trace[2134889306] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"310.704972ms","start":"2026-05-20T21:21:51.805569Z","end":"2026-05-20T21:21:52.116274Z","steps":["trace[2134889306] 'process raft request' (duration: 310.541649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:52.116411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:21:51.805548Z","time spent":"310.792934ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:21:52.116469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.627568ms","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-05-20T21:21:52.116508Z","caller":"traceutil/trace.go:171","msg":"trace[1215291100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"145.712648ms","start":"2026-05-20T21:21:51.970785Z","end":"2026-05-20T21:21:52.116498Z","steps":["trace[1215291100] 'agreement among raft nodes before linearized reading' (duration: 145.576316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:21:52.116751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.588946ms","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-05-20T21:21:52.116827Z","caller":"traceutil/trace.go:171","msg":"trace[1345557562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"159.760089ms","start":"2026-05-20T21:21:51.95705Z","end":"2026-05-20T21:21:52.11681Z","steps":["trace[1345557562] 'agreement among raft nodes before linearized reading' (duration: 159.298521ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:22:09.491539Z","caller":"traceutil/trace.go:171","msg":"trace[758135442] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"101.938594ms","start":"2026-05-20T21:22:09.389576Z","end":"2026-05-20T21:22:09.491515Z","steps":["trace[758135442] 'read index received' (duration: 16.967489ms)","trace[758135442] 'applied index is now lower than readState.Index' (duration: 84.969895ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:22:09.491597Z","caller":"traceutil/trace.go:171","msg":"trace[1418909255] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"106.167745ms","start":"2026-05-20T21:22:09.385396Z","end":"2026-05-20T21:22:09.491564Z","steps":["trace[1418909255] 'process raft request' (duration: 81.223044ms)","trace[1418909255] 'compare' (duration: 24.770037ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:22:09.491797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.202381ms","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-05-20T21:22:09.491838Z","caller":"traceutil/trace.go:171","msg":"trace[706328465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:994; }","duration":"102.277392ms","start":"2026-05-20T21:22:09.389551Z","end":"2026-05-20T21:22:09.491829Z","steps":["trace[706328465] 'agreement among raft nodes before linearized reading' (duration: 102.091939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:22:25.197821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.647482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-f54m9\" ","response":"range_response_count:1 size:4126"} {"level":"info","ts":"2026-05-20T21:22:25.197911Z","caller":"traceutil/trace.go:171","msg":"trace[1677870110] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-f54m9; range_end:; response_count:1; response_revision:1224; }","duration":"115.790115ms","start":"2026-05-20T21:22:25.0821Z","end":"2026-05-20T21:22:25.197891Z","steps":["trace[1677870110] 'agreement among raft nodes before linearized reading' (duration: 115.584782ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:22:45.454197Z","caller":"traceutil/trace.go:171","msg":"trace[1156371541] linearizableReadLoop","detail":"{readStateIndex:1516; appliedIndex:1515; }","duration":"158.427888ms","start":"2026-05-20T21:22:45.295753Z","end":"2026-05-20T21:22:45.454181Z","steps":["trace[1156371541] 'read index received' (duration: 158.301616ms)","trace[1156371541] 'applied index is now lower than readState.Index' (duration: 125.782µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:22:45.454298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.53832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:22:45.454318Z","caller":"traceutil/trace.go:171","msg":"trace[225506735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1468; }","duration":"158.610781ms","start":"2026-05-20T21:22:45.295702Z","end":"2026-05-20T21:22:45.454313Z","steps":["trace[225506735] 'agreement among raft nodes before linearized reading' (duration: 158.56182ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:22:45.454275Z","caller":"traceutil/trace.go:171","msg":"trace[589106204] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"181.41562ms","start":"2026-05-20T21:22:45.272824Z","end":"2026-05-20T21:22:45.454239Z","steps":["trace[589106204] 'process raft request' (duration: 181.269997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:22:45.454455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.320678ms","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-05-20T21:22:45.454534Z","caller":"traceutil/trace.go:171","msg":"trace[1726225430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1468; }","duration":"151.407079ms","start":"2026-05-20T21:22:45.303107Z","end":"2026-05-20T21:22:45.454514Z","steps":["trace[1726225430] 'agreement among raft nodes before linearized reading' (duration: 151.191775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:22:45.454592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.230167ms","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-05-20T21:22:45.454692Z","caller":"traceutil/trace.go:171","msg":"trace[1260116407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1468; }","duration":"150.346539ms","start":"2026-05-20T21:22:45.304325Z","end":"2026-05-20T21:22:45.454671Z","steps":["trace[1260116407] 'agreement among raft nodes before linearized reading' (duration: 150.073264ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:22:57.301938Z","caller":"traceutil/trace.go:171","msg":"trace[852046814] linearizableReadLoop","detail":"{readStateIndex:1770; appliedIndex:1769; }","duration":"118.947659ms","start":"2026-05-20T21:22:57.182968Z","end":"2026-05-20T21:22:57.301916Z","steps":["trace[852046814] 'read index received' (duration: 51.107902ms)","trace[852046814] 'applied index is now lower than readState.Index' (duration: 67.838697ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:22:57.302028Z","caller":"traceutil/trace.go:171","msg":"trace[1580792200] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"119.253464ms","start":"2026-05-20T21:22:57.182746Z","end":"2026-05-20T21:22:57.301999Z","steps":["trace[1580792200] 'process raft request' (duration: 51.418997ms)","trace[1580792200] 'compare' (duration: 67.641894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:22:57.302102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.113712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:22:57.302156Z","caller":"traceutil/trace.go:171","msg":"trace[1154413044] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com; range_end:; response_count:0; response_revision:1701; }","duration":"119.204564ms","start":"2026-05-20T21:22:57.182941Z","end":"2026-05-20T21:22:57.302146Z","steps":["trace[1154413044] 'agreement among raft nodes before linearized reading' (duration: 119.115802ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:03.751424Z","caller":"traceutil/trace.go:171","msg":"trace[1684975278] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"104.888567ms","start":"2026-05-20T21:23:03.64652Z","end":"2026-05-20T21:23:03.751408Z","steps":["trace[1684975278] 'process raft request' (duration: 58.139907ms)","trace[1684975278] 'compare' (duration: 46.476315ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:23:03.752159Z","caller":"traceutil/trace.go:171","msg":"trace[1077731671] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"105.053951ms","start":"2026-05-20T21:23:03.64709Z","end":"2026-05-20T21:23:03.752144Z","steps":["trace[1077731671] 'process raft request' (duration: 104.959819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:12.305214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605946028806930,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T21:23:12.405098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.862579ms","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-05-20T21:23:12.40518Z","caller":"traceutil/trace.go:171","msg":"trace[1926217320] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1894; }","duration":"896.95959ms","start":"2026-05-20T21:23:11.5082Z","end":"2026-05-20T21:23:12.40516Z","steps":["trace[1926217320] 'count revisions from in-memory index tree' (duration: 896.754356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:12.405515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:11.508138Z","time spent":"897.285727ms","remote":"127.0.0.1:33598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-20T21:23:12.40536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.90498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-afeb4185-2030-40f2-90a6-28c7eeac3c90\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-20T21:23:12.405892Z","caller":"traceutil/trace.go:171","msg":"trace[1366684037] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-afeb4185-2030-40f2-90a6-28c7eeac3c90; range_end:; response_count:1; response_revision:1894; }","duration":"732.47781ms","start":"2026-05-20T21:23:11.673385Z","end":"2026-05-20T21:23:12.405863Z","steps":["trace[1366684037] 'range keys from in-memory index tree' (duration: 731.644905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:12.405958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:11.673365Z","time spent":"732.576561ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-afeb4185-2030-40f2-90a6-28c7eeac3c90\" "} {"level":"warn","ts":"2026-05-20T21:23:12.405958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.646936ms","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-05-20T21:23:12.406031Z","caller":"traceutil/trace.go:171","msg":"trace[2070237073] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1894; }","duration":"870.009772ms","start":"2026-05-20T21:23:11.536004Z","end":"2026-05-20T21:23:12.406014Z","steps":["trace[2070237073] 'range keys from in-memory index tree' (duration: 869.505253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:12.406075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:11.535987Z","time spent":"870.075123ms","remote":"127.0.0.1:37322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-20T21:23:12.419094Z","caller":"traceutil/trace.go:171","msg":"trace[836852398] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"742.44897ms","start":"2026-05-20T21:23:11.676631Z","end":"2026-05-20T21:23:12.41908Z","steps":["trace[836852398] 'process raft request' (duration: 742.338438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:12.419209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:11.676618Z","time spent":"742.516772ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:23:12.462448Z","caller":"traceutil/trace.go:171","msg":"trace[641275731] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1970; }","duration":"657.664354ms","start":"2026-05-20T21:23:11.804757Z","end":"2026-05-20T21:23:12.462421Z","steps":["trace[641275731] 'read index received' (duration: 614.245317ms)","trace[641275731] 'applied index is now lower than readState.Index' (duration: 43.418067ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:23:12.462555Z","caller":"traceutil/trace.go:171","msg":"trace[1816897812] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"451.093171ms","start":"2026-05-20T21:23:12.011434Z","end":"2026-05-20T21:23:12.462528Z","steps":["trace[1816897812] 'process raft request' (duration: 450.795855ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:12.462585Z","caller":"traceutil/trace.go:171","msg":"trace[1214745639] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"246.533615ms","start":"2026-05-20T21:23:12.216037Z","end":"2026-05-20T21:23:12.462571Z","steps":["trace[1214745639] 'process raft request' (duration: 246.301831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:12.462835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:12.011409Z","time spent":"451.194491ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:23:12.462875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.115742ms","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-05-20T21:23:12.462925Z","caller":"traceutil/trace.go:171","msg":"trace[903464613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1897; }","duration":"658.197943ms","start":"2026-05-20T21:23:11.804712Z","end":"2026-05-20T21:23:12.46291Z","steps":["trace[903464613] 'agreement among raft nodes before linearized reading' (duration: 657.995649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:12.462966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:11.804692Z","time spent":"658.263634ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T21:23:12.463272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.69084ms","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-05-20T21:23:12.463443Z","caller":"traceutil/trace.go:171","msg":"trace[1886606494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1897; }","duration":"654.929104ms","start":"2026-05-20T21:23:11.808483Z","end":"2026-05-20T21:23:12.463412Z","steps":["trace[1886606494] 'agreement among raft nodes before linearized reading' (duration: 654.491737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:12.463479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.512495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-afeb4185-2030-40f2-90a6-28c7eeac3c90\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-20T21:23:12.46332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.032999ms","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-05-20T21:23:12.463618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:11.808454Z","time spent":"655.142727ms","remote":"127.0.0.1:50172","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-05-20T21:23:12.463334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.138366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:23:12.46408Z","caller":"traceutil/trace.go:171","msg":"trace[423902256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1897; }","duration":"168.89927ms","start":"2026-05-20T21:23:12.295153Z","end":"2026-05-20T21:23:12.464052Z","steps":["trace[423902256] 'agreement among raft nodes before linearized reading' (duration: 168.134436ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:12.464198Z","caller":"traceutil/trace.go:171","msg":"trace[539775074] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-afeb4185-2030-40f2-90a6-28c7eeac3c90; range_end:; response_count:1; response_revision:1897; }","duration":"290.309208ms","start":"2026-05-20T21:23:12.17387Z","end":"2026-05-20T21:23:12.46418Z","steps":["trace[539775074] 'agreement among raft nodes before linearized reading' (duration: 289.094047ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:12.464245Z","caller":"traceutil/trace.go:171","msg":"trace[1392742372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1897; }","duration":"478.342394ms","start":"2026-05-20T21:23:11.985238Z","end":"2026-05-20T21:23:12.46358Z","steps":["trace[1392742372] 'agreement among raft nodes before linearized reading' (duration: 477.985588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:12.464393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:11.985217Z","time spent":"479.15726ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-20T21:23:12.63157Z","caller":"traceutil/trace.go:171","msg":"trace[838056840] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"162.063656ms","start":"2026-05-20T21:23:12.469475Z","end":"2026-05-20T21:23:12.631539Z","steps":["trace[838056840] 'process raft request' (duration: 128.154171ms)","trace[838056840] 'compare' (duration: 33.399685ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:23:12.675941Z","caller":"traceutil/trace.go:171","msg":"trace[2081813874] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"206.40761ms","start":"2026-05-20T21:23:12.469516Z","end":"2026-05-20T21:23:12.675924Z","steps":["trace[2081813874] 'process raft request' (duration: 206.248077ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:12.676012Z","caller":"traceutil/trace.go:171","msg":"trace[362042870] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"206.429109ms","start":"2026-05-20T21:23:12.469549Z","end":"2026-05-20T21:23:12.675978Z","steps":["trace[362042870] 'process raft request' (duration: 206.306196ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:12.676169Z","caller":"traceutil/trace.go:171","msg":"trace[1205140263] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"203.907204ms","start":"2026-05-20T21:23:12.472215Z","end":"2026-05-20T21:23:12.676122Z","steps":["trace[1205140263] 'process raft request' (duration: 203.66663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:13.352045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.721037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:23:13.352122Z","caller":"traceutil/trace.go:171","msg":"trace[2058171998] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1903; }","duration":"468.84474ms","start":"2026-05-20T21:23:12.883257Z","end":"2026-05-20T21:23:13.352102Z","steps":["trace[2058171998] 'count revisions from in-memory index tree' (duration: 468.615475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:13.352164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:12.883233Z","time spent":"468.919271ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-20T21:23:13.352615Z","caller":"traceutil/trace.go:171","msg":"trace[1466631053] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"359.075491ms","start":"2026-05-20T21:23:12.993521Z","end":"2026-05-20T21:23:13.352596Z","steps":["trace[1466631053] 'process raft request' (duration: 295.646452ms)","trace[1466631053] 'compare' (duration: 63.196005ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:23:13.352713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:12.993495Z","time spent":"359.159062ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:23:18.545805Z","caller":"traceutil/trace.go:171","msg":"trace[908284270] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"108.199905ms","start":"2026-05-20T21:23:18.437581Z","end":"2026-05-20T21:23:18.545781Z","steps":["trace[908284270] 'process raft request' (duration: 108.015912ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:24.69223Z","caller":"traceutil/trace.go:171","msg":"trace[1384925608] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"117.133461ms","start":"2026-05-20T21:23:24.575071Z","end":"2026-05-20T21:23:24.692205Z","steps":["trace[1384925608] 'process raft request' (duration: 92.842084ms)","trace[1384925608] 'compare' (duration: 24.153735ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:23:24.69246Z","caller":"traceutil/trace.go:171","msg":"trace[753595853] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"110.558373ms","start":"2026-05-20T21:23:24.581873Z","end":"2026-05-20T21:23:24.692431Z","steps":["trace[753595853] 'process raft request' (duration: 110.296809ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:24.692354Z","caller":"traceutil/trace.go:171","msg":"trace[585406830] linearizableReadLoop","detail":"{readStateIndex:2078; appliedIndex:2075; }","duration":"107.01381ms","start":"2026-05-20T21:23:24.585318Z","end":"2026-05-20T21:23:24.692332Z","steps":["trace[585406830] 'read index received' (duration: 259.305µs)","trace[585406830] 'applied index is now lower than readState.Index' (duration: 106.752905ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:23:24.692837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.502359ms","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-05-20T21:23:24.692903Z","caller":"traceutil/trace.go:171","msg":"trace[1220557268] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1999; }","duration":"107.58026ms","start":"2026-05-20T21:23:24.585304Z","end":"2026-05-20T21:23:24.692884Z","steps":["trace[1220557268] 'agreement among raft nodes before linearized reading' (duration: 107.215073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:24.951131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.188238ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-20T21:23:24.951667Z","caller":"traceutil/trace.go:171","msg":"trace[1506926403] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"203.168563ms","start":"2026-05-20T21:23:24.748475Z","end":"2026-05-20T21:23:24.951644Z","steps":["trace[1506926403] 'process raft request' (duration: 203.075131ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:24.951773Z","caller":"traceutil/trace.go:171","msg":"trace[2050249354] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"203.460499ms","start":"2026-05-20T21:23:24.748299Z","end":"2026-05-20T21:23:24.95176Z","steps":["trace[2050249354] 'process raft request' (duration: 203.054343ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:24.95191Z","caller":"traceutil/trace.go:171","msg":"trace[612410419] linearizableReadLoop","detail":"{readStateIndex:2083; appliedIndex:2079; }","duration":"175.411482ms","start":"2026-05-20T21:23:24.776473Z","end":"2026-05-20T21:23:24.951885Z","steps":["trace[612410419] 'read index received' (duration: 44.472482ms)","trace[612410419] 'applied index is now lower than readState.Index' (duration: 130.93701ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:23:24.952072Z","caller":"traceutil/trace.go:171","msg":"trace[987131958] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"203.253286ms","start":"2026-05-20T21:23:24.748796Z","end":"2026-05-20T21:23:24.952049Z","steps":["trace[987131958] 'process raft request' (duration: 202.804228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:24.95222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.738318ms","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-05-20T21:23:24.952337Z","caller":"traceutil/trace.go:171","msg":"trace[354198056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2003; }","duration":"175.843882ms","start":"2026-05-20T21:23:24.776433Z","end":"2026-05-20T21:23:24.952277Z","steps":["trace[354198056] 'agreement among raft nodes before linearized reading' (duration: 175.650678ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:29.127718Z","caller":"traceutil/trace.go:171","msg":"trace[1255751552] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"587.032232ms","start":"2026-05-20T21:23:28.540661Z","end":"2026-05-20T21:23:29.127693Z","steps":["trace[1255751552] 'process raft request' (duration: 586.853879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:29.127874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:28.540638Z","time spent":"587.159615ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:23:29.231186Z","caller":"traceutil/trace.go:171","msg":"trace[264809278] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"628.372276ms","start":"2026-05-20T21:23:28.602793Z","end":"2026-05-20T21:23:29.231165Z","steps":["trace[264809278] 'process raft request' (duration: 628.190443ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:29.231206Z","caller":"traceutil/trace.go:171","msg":"trace[2030216272] linearizableReadLoop","detail":"{readStateIndex:2113; appliedIndex:2109; }","duration":"511.075594ms","start":"2026-05-20T21:23:28.720109Z","end":"2026-05-20T21:23:29.231185Z","steps":["trace[2030216272] 'read index received' (duration: 407.501869ms)","trace[2030216272] 'applied index is now lower than readState.Index' (duration: 103.572684ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:23:29.231262Z","caller":"traceutil/trace.go:171","msg":"trace[610168585] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"528.320015ms","start":"2026-05-20T21:23:28.702922Z","end":"2026-05-20T21:23:29.231242Z","steps":["trace[610168585] 'process raft request' (duration: 528.178632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:29.231295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:28.602777Z","time spent":"628.462958ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:23:29.231309Z","caller":"traceutil/trace.go:171","msg":"trace[1870838788] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"527.348977ms","start":"2026-05-20T21:23:28.703951Z","end":"2026-05-20T21:23:29.2313Z","steps":["trace[1870838788] 'process raft request' (duration: 527.200724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:29.231352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:28.702901Z","time spent":"528.390665ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:23:29.23137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:28.703946Z","time spent":"527.385108ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:23:29.231445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.527247ms","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-05-20T21:23:29.231475Z","caller":"traceutil/trace.go:171","msg":"trace[322138769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2033; }","duration":"474.586487ms","start":"2026-05-20T21:23:28.75688Z","end":"2026-05-20T21:23:29.231466Z","steps":["trace[322138769] 'agreement among raft nodes before linearized reading' (duration: 474.487935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:29.23149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.385833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-20T21:23:29.231499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:28.756862Z","time spent":"474.633117ms","remote":"127.0.0.1:50172","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-05-20T21:23:29.231525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.422419ms","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-05-20T21:23:29.231537Z","caller":"traceutil/trace.go:171","msg":"trace[1852472995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2033; }","duration":"263.428123ms","start":"2026-05-20T21:23:28.968074Z","end":"2026-05-20T21:23:29.231502Z","steps":["trace[1852472995] 'agreement among raft nodes before linearized reading' (duration: 263.370943ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:29.231547Z","caller":"traceutil/trace.go:171","msg":"trace[1040864014] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2033; }","duration":"511.483052ms","start":"2026-05-20T21:23:28.720057Z","end":"2026-05-20T21:23:29.23154Z","steps":["trace[1040864014] 'agreement among raft nodes before linearized reading' (duration: 511.39196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:29.231564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:28.720035Z","time spent":"511.525622ms","remote":"127.0.0.1:37322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-20T21:23:29.231581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.644234ms","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-05-20T21:23:29.231599Z","caller":"traceutil/trace.go:171","msg":"trace[1685336270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2033; }","duration":"265.681124ms","start":"2026-05-20T21:23:28.965912Z","end":"2026-05-20T21:23:29.231593Z","steps":["trace[1685336270] 'agreement among raft nodes before linearized reading' (duration: 265.629924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:29.231628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.283411ms","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-05-20T21:23:29.231644Z","caller":"traceutil/trace.go:171","msg":"trace[403638322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2033; }","duration":"217.338994ms","start":"2026-05-20T21:23:29.0143Z","end":"2026-05-20T21:23:29.231639Z","steps":["trace[403638322] 'agreement among raft nodes before linearized reading' (duration: 217.285453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:29.231705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.178451ms","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-05-20T21:23:29.231726Z","caller":"traceutil/trace.go:171","msg":"trace[837731858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2033; }","duration":"267.232112ms","start":"2026-05-20T21:23:28.964487Z","end":"2026-05-20T21:23:29.231719Z","steps":["trace[837731858] 'agreement among raft nodes before linearized reading' (duration: 267.181902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:29.231739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.90478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:23:29.231759Z","caller":"traceutil/trace.go:171","msg":"trace[799064744] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2033; }","duration":"344.954203ms","start":"2026-05-20T21:23:28.886799Z","end":"2026-05-20T21:23:29.231753Z","steps":["trace[799064744] 'agreement among raft nodes before linearized reading' (duration: 344.920872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:29.231784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:28.886782Z","time spent":"344.996024ms","remote":"127.0.0.1:33574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-05-20T21:23:51.569537Z","caller":"traceutil/trace.go:171","msg":"trace[1306572082] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"180.241109ms","start":"2026-05-20T21:23:51.389275Z","end":"2026-05-20T21:23:51.569516Z","steps":["trace[1306572082] 'process raft request' (duration: 170.808157ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:51.569913Z","caller":"traceutil/trace.go:171","msg":"trace[1851029412] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"180.5854ms","start":"2026-05-20T21:23:51.389287Z","end":"2026-05-20T21:23:51.569872Z","steps":["trace[1851029412] 'process raft request' (duration: 180.191237ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:51.570024Z","caller":"traceutil/trace.go:171","msg":"trace[131059333] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"180.234799ms","start":"2026-05-20T21:23:51.38978Z","end":"2026-05-20T21:23:51.570015Z","steps":["trace[131059333] 'process raft request' (duration: 180.084583ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:51.570129Z","caller":"traceutil/trace.go:171","msg":"trace[74001507] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2243; }","duration":"175.058496ms","start":"2026-05-20T21:23:51.394932Z","end":"2026-05-20T21:23:51.56999Z","steps":["trace[74001507] 'read index received' (duration: 40.264234ms)","trace[74001507] 'applied index is now lower than readState.Index' (duration: 134.793352ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:23:51.570161Z","caller":"traceutil/trace.go:171","msg":"trace[1099487231] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"180.219688ms","start":"2026-05-20T21:23:51.389917Z","end":"2026-05-20T21:23:51.570136Z","steps":["trace[1099487231] 'process raft request' (duration: 180.035401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:51.570526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.569164ms","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-05-20T21:23:51.570592Z","caller":"traceutil/trace.go:171","msg":"trace[1910420448] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2163; }","duration":"175.665198ms","start":"2026-05-20T21:23:51.394906Z","end":"2026-05-20T21:23:51.570571Z","steps":["trace[1910420448] 'agreement among raft nodes before linearized reading' (duration: 175.308495ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:53.809315Z","caller":"traceutil/trace.go:171","msg":"trace[1724150479] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"420.073799ms","start":"2026-05-20T21:23:53.389215Z","end":"2026-05-20T21:23:53.809289Z","steps":["trace[1724150479] 'process raft request' (duration: 419.846381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:53.809465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:53.389197Z","time spent":"420.195224ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:23:53.809629Z","caller":"traceutil/trace.go:171","msg":"trace[482559435] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2250; }","duration":"370.876424ms","start":"2026-05-20T21:23:53.438727Z","end":"2026-05-20T21:23:53.809603Z","steps":["trace[482559435] 'read index received' (duration: 370.320515ms)","trace[482559435] 'applied index is now lower than readState.Index' (duration: 553.649µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:23:53.809714Z","caller":"traceutil/trace.go:171","msg":"trace[1940746056] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"416.530816ms","start":"2026-05-20T21:23:53.393167Z","end":"2026-05-20T21:23:53.809698Z","steps":["trace[1940746056] 'process raft request' (duration: 416.070621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:53.809797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:53.393152Z","time spent":"416.601519ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:23:53.81015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.425723ms","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-05-20T21:23:53.810207Z","caller":"traceutil/trace.go:171","msg":"trace[1375943778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2167; }","duration":"371.521236ms","start":"2026-05-20T21:23:53.438669Z","end":"2026-05-20T21:23:53.810191Z","steps":["trace[1375943778] 'agreement among raft nodes before linearized reading' (duration: 371.070319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:53.81035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:23:53.438649Z","time spent":"371.685502ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T21:23:53.812434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.810378ms","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-05-20T21:23:53.812589Z","caller":"traceutil/trace.go:171","msg":"trace[1698088117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2168; }","duration":"238.987354ms","start":"2026-05-20T21:23:53.573553Z","end":"2026-05-20T21:23:53.81254Z","steps":["trace[1698088117] 'agreement among raft nodes before linearized reading' (duration: 238.727635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:53.812816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.940754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-20T21:23:53.812861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.209547ms","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-05-20T21:23:53.812872Z","caller":"traceutil/trace.go:171","msg":"trace[431512742] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2168; }","duration":"228.993575ms","start":"2026-05-20T21:23:53.583861Z","end":"2026-05-20T21:23:53.812855Z","steps":["trace[431512742] 'agreement among raft nodes before linearized reading' (duration: 228.760847ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:53.812905Z","caller":"traceutil/trace.go:171","msg":"trace[1780392959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2168; }","duration":"238.275ms","start":"2026-05-20T21:23:53.574618Z","end":"2026-05-20T21:23:53.812893Z","steps":["trace[1780392959] 'agreement among raft nodes before linearized reading' (duration: 238.160396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:53.813052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.182711ms","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-05-20T21:23:53.813129Z","caller":"traceutil/trace.go:171","msg":"trace[325233891] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"235.39666ms","start":"2026-05-20T21:23:53.577719Z","end":"2026-05-20T21:23:53.813116Z","steps":["trace[325233891] 'process raft request' (duration: 234.449046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:23:53.813097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.394328ms","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-05-20T21:23:53.81329Z","caller":"traceutil/trace.go:171","msg":"trace[1175728494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2168; }","duration":"239.650427ms","start":"2026-05-20T21:23:53.573627Z","end":"2026-05-20T21:23:53.813277Z","steps":["trace[1175728494] 'agreement among raft nodes before linearized reading' (duration: 239.339296ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:23:53.813285Z","caller":"traceutil/trace.go:171","msg":"trace[1322677869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2168; }","duration":"239.309025ms","start":"2026-05-20T21:23:53.573811Z","end":"2026-05-20T21:23:53.81312Z","steps":["trace[1322677869] 'agreement among raft nodes before linearized reading' (duration: 238.948612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:24:50.675446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.915446ms","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":"warn","ts":"2026-05-20T21:24:50.67543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.223622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T21:24:50.675484Z","caller":"traceutil/trace.go:171","msg":"trace[954624540] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2583; }","duration":"127.982079ms","start":"2026-05-20T21:24:50.547492Z","end":"2026-05-20T21:24:50.675474Z","steps":["trace[954624540] 'count revisions from in-memory index tree' (duration: 127.869265ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:24:50.675493Z","caller":"traceutil/trace.go:171","msg":"trace[2090686906] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2583; }","duration":"134.320906ms","start":"2026-05-20T21:24:50.541158Z","end":"2026-05-20T21:24:50.675479Z","steps":["trace[2090686906] 'count revisions from in-memory index tree' (duration: 134.153971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:24:50.675413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.65188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:24:50.675637Z","caller":"traceutil/trace.go:171","msg":"trace[1704212514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2583; }","duration":"380.951157ms","start":"2026-05-20T21:24:50.294679Z","end":"2026-05-20T21:24:50.67563Z","steps":["trace[1704212514] 'range keys from in-memory index tree' (duration: 380.573397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:24:50.675654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:24:50.294663Z","time spent":"380.985648ms","remote":"127.0.0.1:49898","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-05-20T21:24:50.675832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.809756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-20T21:24:50.675874Z","caller":"traceutil/trace.go:171","msg":"trace[627407254] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2583; }","duration":"304.882608ms","start":"2026-05-20T21:24:50.370981Z","end":"2026-05-20T21:24:50.675864Z","steps":["trace[627407254] 'range keys from in-memory index tree' (duration: 304.699863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:24:50.6759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:24:50.370968Z","time spent":"304.925389ms","remote":"127.0.0.1:50110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-05-20T21:25:23.922918Z","caller":"traceutil/trace.go:171","msg":"trace[1653830674] linearizableReadLoop","detail":"{readStateIndex:2867; appliedIndex:2866; }","duration":"155.073157ms","start":"2026-05-20T21:25:23.767821Z","end":"2026-05-20T21:25:23.922895Z","steps":["trace[1653830674] 'read index received' (duration: 155.014145ms)","trace[1653830674] 'applied index is now lower than readState.Index' (duration: 57.762µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:25:23.922971Z","caller":"traceutil/trace.go:171","msg":"trace[975198487] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"251.051172ms","start":"2026-05-20T21:25:23.671897Z","end":"2026-05-20T21:25:23.922948Z","steps":["trace[975198487] 'process raft request' (duration: 250.853506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:25:23.923106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.261892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:25:23.923155Z","caller":"traceutil/trace.go:171","msg":"trace[575780471] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2761; }","duration":"155.326723ms","start":"2026-05-20T21:25:23.767816Z","end":"2026-05-20T21:25:23.923142Z","steps":["trace[575780471] 'agreement among raft nodes before linearized reading' (duration: 155.20822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:25:24.864452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.95191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T21:25:24.864629Z","caller":"traceutil/trace.go:171","msg":"trace[2080728820] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"1.063878083s","start":"2026-05-20T21:25:23.800728Z","end":"2026-05-20T21:25:24.864606Z","steps":["trace[2080728820] 'process raft request' (duration: 429.700407ms)","trace[2080728820] 'compare' (duration: 633.058136ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:25:24.864702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:25:23.800706Z","time spent":"1.063964866s","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:25:24.870907Z","caller":"traceutil/trace.go:171","msg":"trace[1745489882] linearizableReadLoop","detail":"{readStateIndex:2871; appliedIndex:2867; }","duration":"674.862223ms","start":"2026-05-20T21:25:24.196013Z","end":"2026-05-20T21:25:24.870875Z","steps":["trace[1745489882] 'read index received' (duration: 34.58592ms)","trace[1745489882] 'applied index is now lower than readState.Index' (duration: 640.275143ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:25:24.871146Z","caller":"traceutil/trace.go:171","msg":"trace[2105832906] transaction","detail":"{read_only:false; response_revision:2765; number_of_response:1; }","duration":"683.037085ms","start":"2026-05-20T21:25:24.188094Z","end":"2026-05-20T21:25:24.871131Z","steps":["trace[2105832906] 'process raft request' (duration: 682.723056ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:25:24.871129Z","caller":"traceutil/trace.go:171","msg":"trace[1806897729] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"698.954799ms","start":"2026-05-20T21:25:24.172128Z","end":"2026-05-20T21:25:24.871083Z","steps":["trace[1806897729] 'process raft request' (duration: 698.612399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:25:24.871296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.42821ms","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-05-20T21:25:24.871338Z","caller":"traceutil/trace.go:171","msg":"trace[1438961413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2765; }","duration":"674.491942ms","start":"2026-05-20T21:25:24.196834Z","end":"2026-05-20T21:25:24.871326Z","steps":["trace[1438961413] 'agreement among raft nodes before linearized reading' (duration: 674.345278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:25:24.871361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:25:24.196801Z","time spent":"674.554774ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T21:25:24.871354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:25:24.188082Z","time spent":"683.105697ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:25:24.871497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.483079ms","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-05-20T21:25:24.871518Z","caller":"traceutil/trace.go:171","msg":"trace[13636992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2765; }","duration":"675.50493ms","start":"2026-05-20T21:25:24.196006Z","end":"2026-05-20T21:25:24.871511Z","steps":["trace[13636992] 'agreement among raft nodes before linearized reading' (duration: 675.449778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:25:24.87154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:25:24.195948Z","time spent":"675.586442ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T21:25:24.871668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.172764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T21:25:24.87169Z","caller":"traceutil/trace.go:171","msg":"trace[1429904649] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:2765; }","duration":"132.287248ms","start":"2026-05-20T21:25:24.739397Z","end":"2026-05-20T21:25:24.871685Z","steps":["trace[1429904649] 'agreement among raft nodes before linearized reading' (duration: 132.216006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:25:24.871471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:25:24.172106Z","time spent":"699.147553ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:25:24.871744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.278194ms","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-05-20T21:25:24.873133Z","caller":"traceutil/trace.go:171","msg":"trace[169975572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2765; }","duration":"601.693163ms","start":"2026-05-20T21:25:24.27142Z","end":"2026-05-20T21:25:24.873114Z","steps":["trace[169975572] 'agreement among raft nodes before linearized reading' (duration: 600.215484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:25:24.873201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:25:24.271403Z","time spent":"601.776795ms","remote":"127.0.0.1:50172","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-05-20T21:25:24.871804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.370554ms","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-05-20T21:25:24.873942Z","caller":"traceutil/trace.go:171","msg":"trace[163627837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2765; }","duration":"603.529593ms","start":"2026-05-20T21:25:24.270397Z","end":"2026-05-20T21:25:24.873926Z","steps":["trace[163627837] 'agreement among raft nodes before linearized reading' (duration: 601.372064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:25:24.871818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.107857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-20T21:25:24.874032Z","caller":"traceutil/trace.go:171","msg":"trace[761034275] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2765; }","duration":"503.362108ms","start":"2026-05-20T21:25:24.370658Z","end":"2026-05-20T21:25:24.87402Z","steps":["trace[761034275] 'agreement among raft nodes before linearized reading' (duration: 501.106227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:25:24.874077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:25:24.370643Z","time spent":"503.4162ms","remote":"127.0.0.1:50110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-20T21:25:24.874081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:25:24.27037Z","time spent":"603.637725ms","remote":"127.0.0.1:50172","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-05-20T21:25:24.871872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.531284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-20T21:25:24.874219Z","caller":"traceutil/trace.go:171","msg":"trace[199751502] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2765; }","duration":"601.893429ms","start":"2026-05-20T21:25:24.272317Z","end":"2026-05-20T21:25:24.874211Z","steps":["trace[199751502] 'agreement among raft nodes before linearized reading' (duration: 599.517994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:25:24.874249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:25:24.27231Z","time spent":"601.930609ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-20T21:25:24.871926Z","caller":"traceutil/trace.go:171","msg":"trace[435274969] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"704.53268ms","start":"2026-05-20T21:25:24.167385Z","end":"2026-05-20T21:25:24.871917Z","steps":["trace[435274969] 'process raft request' (duration: 703.237425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:25:24.87444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:25:24.167359Z","time spent":"707.027877ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:28:07.583591Z","caller":"traceutil/trace.go:171","msg":"trace[1950758036] transaction","detail":"{read_only:false; response_revision:3751; number_of_response:1; }","duration":"106.424848ms","start":"2026-05-20T21:28:07.477139Z","end":"2026-05-20T21:28:07.583564Z","steps":["trace[1950758036] 'process raft request' (duration: 54.050783ms)","trace[1950758036] 'compare' (duration: 52.231992ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:28:09.185476Z","caller":"traceutil/trace.go:171","msg":"trace[164638594] transaction","detail":"{read_only:false; response_revision:3775; number_of_response:1; }","duration":"128.358234ms","start":"2026-05-20T21:28:09.057079Z","end":"2026-05-20T21:28:09.185437Z","steps":["trace[164638594] 'process raft request' (duration: 72.522655ms)","trace[164638594] 'compare' (duration: 55.565513ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:28:09.186482Z","caller":"traceutil/trace.go:171","msg":"trace[1636071954] transaction","detail":"{read_only:false; response_revision:3776; number_of_response:1; }","duration":"102.087741ms","start":"2026-05-20T21:28:09.084371Z","end":"2026-05-20T21:28:09.186458Z","steps":["trace[1636071954] 'process raft request' (duration: 101.239432ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:28:09.494452Z","caller":"traceutil/trace.go:171","msg":"trace[883004414] linearizableReadLoop","detail":"{readStateIndex:3924; appliedIndex:3923; }","duration":"200.687523ms","start":"2026-05-20T21:28:09.293739Z","end":"2026-05-20T21:28:09.494426Z","steps":["trace[883004414] 'read index received' (duration: 104.360599ms)","trace[883004414] 'applied index is now lower than readState.Index' (duration: 96.325544ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:28:09.494521Z","caller":"traceutil/trace.go:171","msg":"trace[104884300] transaction","detail":"{read_only:false; response_revision:3778; number_of_response:1; }","duration":"242.868725ms","start":"2026-05-20T21:28:09.251615Z","end":"2026-05-20T21:28:09.494484Z","steps":["trace[104884300] 'process raft request' (duration: 146.451609ms)","trace[104884300] 'compare' (duration: 96.128099ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:28:09.494665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.905888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:28:09.495124Z","caller":"traceutil/trace.go:171","msg":"trace[1425264460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3778; }","duration":"201.403949ms","start":"2026-05-20T21:28:09.293702Z","end":"2026-05-20T21:28:09.495106Z","steps":["trace[1425264460] 'agreement among raft nodes before linearized reading' (duration: 200.883778ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:28:09.500335Z","caller":"traceutil/trace.go:171","msg":"trace[2001903122] transaction","detail":"{read_only:false; response_revision:3779; number_of_response:1; }","duration":"199.675922ms","start":"2026-05-20T21:28:09.300643Z","end":"2026-05-20T21:28:09.500319Z","steps":["trace[2001903122] 'process raft request' (duration: 199.090939ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:28:09.678619Z","caller":"traceutil/trace.go:171","msg":"trace[1816689762] transaction","detail":"{read_only:false; response_revision:3784; number_of_response:1; }","duration":"100.128467ms","start":"2026-05-20T21:28:09.578456Z","end":"2026-05-20T21:28:09.678585Z","steps":["trace[1816689762] 'process raft request' (duration: 99.794879ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:28:11.083889Z","caller":"traceutil/trace.go:171","msg":"trace[544944771] transaction","detail":"{read_only:false; response_revision:3799; number_of_response:1; }","duration":"136.448077ms","start":"2026-05-20T21:28:10.947397Z","end":"2026-05-20T21:28:11.083845Z","steps":["trace[544944771] 'process raft request' (duration: 52.856604ms)","trace[544944771] 'compare' (duration: 83.44222ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:29:00.861032Z","caller":"traceutil/trace.go:171","msg":"trace[77818666] transaction","detail":"{read_only:false; response_revision:4587; number_of_response:1; }","duration":"120.89758ms","start":"2026-05-20T21:29:00.74011Z","end":"2026-05-20T21:29:00.861007Z","steps":["trace[77818666] 'process raft request' (duration: 120.693526ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:00.867974Z","caller":"traceutil/trace.go:171","msg":"trace[1856830250] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"121.164246ms","start":"2026-05-20T21:29:00.746775Z","end":"2026-05-20T21:29:00.867939Z","steps":["trace[1856830250] 'process raft request' (duration: 120.957221ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:06.597785Z","caller":"traceutil/trace.go:171","msg":"trace[1652687932] linearizableReadLoop","detail":"{readStateIndex:4882; appliedIndex:4881; }","duration":"309.643998ms","start":"2026-05-20T21:29:06.288089Z","end":"2026-05-20T21:29:06.597733Z","steps":["trace[1652687932] 'read index received' (duration: 309.361713ms)","trace[1652687932] 'applied index is now lower than readState.Index' (duration: 275.945µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:29:06.59819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.090647ms","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":"warn","ts":"2026-05-20T21:29:06.598239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.931465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:29:06.598289Z","caller":"traceutil/trace.go:171","msg":"trace[1849748918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4703; }","duration":"302.045606ms","start":"2026-05-20T21:29:06.296233Z","end":"2026-05-20T21:29:06.598278Z","steps":["trace[1849748918] 'agreement among raft nodes before linearized reading' (duration: 301.933244ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:06.598294Z","caller":"traceutil/trace.go:171","msg":"trace[55047390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4703; }","duration":"310.416954ms","start":"2026-05-20T21:29:06.287844Z","end":"2026-05-20T21:29:06.598261Z","steps":["trace[55047390] 'agreement among raft nodes before linearized reading' (duration: 310.20631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:06.598388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:06.287821Z","time spent":"310.557457ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T21:29:06.598567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.179302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0d4d22f4-ff0a-4575-922d-bfae122b4698\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-05-20T21:29:06.598328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:06.296213Z","time spent":"302.10708ms","remote":"127.0.0.1:49898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-20T21:29:06.598608Z","caller":"traceutil/trace.go:171","msg":"trace[610998926] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0d4d22f4-ff0a-4575-922d-bfae122b4698; range_end:; response_count:1; response_revision:4703; }","duration":"168.218962ms","start":"2026-05-20T21:29:06.430378Z","end":"2026-05-20T21:29:06.598597Z","steps":["trace[610998926] 'agreement among raft nodes before linearized reading' (duration: 168.08014ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:06.598587Z","caller":"traceutil/trace.go:171","msg":"trace[2105940505] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"342.41252ms","start":"2026-05-20T21:29:06.256165Z","end":"2026-05-20T21:29:06.598577Z","steps":["trace[2105940505] 'process raft request' (duration: 341.374108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:06.598727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:06.256144Z","time spent":"342.541223ms","remote":"127.0.0.1:50092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:29:07.156339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.079395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T21:29:07.156597Z","caller":"traceutil/trace.go:171","msg":"trace[1809636287] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"242.488528ms","start":"2026-05-20T21:29:06.91409Z","end":"2026-05-20T21:29:07.156578Z","steps":["trace[1809636287] 'process raft request' (duration: 242.370036ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:07.156799Z","caller":"traceutil/trace.go:171","msg":"trace[2126665878] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"553.437964ms","start":"2026-05-20T21:29:06.603344Z","end":"2026-05-20T21:29:07.156782Z","steps":["trace[2126665878] 'process raft request' (duration: 305.827878ms)","trace[2126665878] 'compare' (duration: 246.034884ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:29:07.156831Z","caller":"traceutil/trace.go:171","msg":"trace[1500107772] linearizableReadLoop","detail":"{readStateIndex:4883; appliedIndex:4882; }","duration":"552.800321ms","start":"2026-05-20T21:29:06.604018Z","end":"2026-05-20T21:29:07.156818Z","steps":["trace[1500107772] 'read index received' (duration: 305.079511ms)","trace[1500107772] 'applied index is now lower than readState.Index' (duration: 247.71977ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:29:07.15688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:06.60333Z","time spent":"553.500406ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:29:07.157031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.080186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0d4d22f4-ff0a-4575-922d-bfae122b4698\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-20T21:29:07.15707Z","caller":"traceutil/trace.go:171","msg":"trace[123512171] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0d4d22f4-ff0a-4575-922d-bfae122b4698; range_end:; response_count:1; response_revision:4705; }","duration":"239.167539ms","start":"2026-05-20T21:29:06.917892Z","end":"2026-05-20T21:29:07.15706Z","steps":["trace[123512171] 'agreement among raft nodes before linearized reading' (duration: 239.018085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:07.157071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.870544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-05-20T21:29:07.15706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.018265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10319"} {"level":"info","ts":"2026-05-20T21:29:07.157119Z","caller":"traceutil/trace.go:171","msg":"trace[724955884] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4705; }","duration":"168.971916ms","start":"2026-05-20T21:29:06.988137Z","end":"2026-05-20T21:29:07.157109Z","steps":["trace[724955884] 'agreement among raft nodes before linearized reading' (duration: 168.875654ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:07.157141Z","caller":"traceutil/trace.go:171","msg":"trace[19885867] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4705; }","duration":"553.121037ms","start":"2026-05-20T21:29:06.604Z","end":"2026-05-20T21:29:07.157121Z","steps":["trace[19885867] 'agreement among raft nodes before linearized reading' (duration: 552.900153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:07.157187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:06.603991Z","time spent":"553.187239ms","remote":"127.0.0.1:50092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":10342,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-20T21:29:07.440152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.450671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-20T21:29:07.440239Z","caller":"traceutil/trace.go:171","msg":"trace[2121533454] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4707; }","duration":"182.555314ms","start":"2026-05-20T21:29:07.257663Z","end":"2026-05-20T21:29:07.440218Z","steps":["trace[2121533454] 'range keys from in-memory index tree' (duration: 182.282858ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:07.441038Z","caller":"traceutil/trace.go:171","msg":"trace[1589426232] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"175.28427ms","start":"2026-05-20T21:29:07.265733Z","end":"2026-05-20T21:29:07.441017Z","steps":["trace[1589426232] 'process raft request' (duration: 139.530165ms)","trace[1589426232] 'compare' (duration: 34.953788ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:29:07.471019Z","caller":"traceutil/trace.go:171","msg":"trace[909877806] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"166.061455ms","start":"2026-05-20T21:29:07.304934Z","end":"2026-05-20T21:29:07.470995Z","steps":["trace[909877806] 'process raft request' (duration: 165.702538ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:07.862962Z","caller":"traceutil/trace.go:171","msg":"trace[1191558835] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"415.678482ms","start":"2026-05-20T21:29:07.447253Z","end":"2026-05-20T21:29:07.862931Z","steps":["trace[1191558835] 'process raft request' (duration: 344.097102ms)","trace[1191558835] 'compare' (duration: 71.115011ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:29:07.863147Z","caller":"traceutil/trace.go:171","msg":"trace[858732077] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"414.870576ms","start":"2026-05-20T21:29:07.448245Z","end":"2026-05-20T21:29:07.863116Z","steps":["trace[858732077] 'process raft request' (duration: 414.601299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:07.863145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:07.447235Z","time spent":"415.794836ms","remote":"127.0.0.1:50174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:29:07.863259Z","caller":"traceutil/trace.go:171","msg":"trace[147828526] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"414.752243ms","start":"2026-05-20T21:29:07.448467Z","end":"2026-05-20T21:29:07.863219Z","steps":["trace[147828526] 'process raft request' (duration: 414.589209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:07.863389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:07.448237Z","time spent":"415.032808ms","remote":"127.0.0.1:50174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:29:07.863506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:07.448451Z","time spent":"414.891475ms","remote":"127.0.0.1:50076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:29:07.86356Z","caller":"traceutil/trace.go:171","msg":"trace[1895152459] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"131.612477ms","start":"2026-05-20T21:29:07.731917Z","end":"2026-05-20T21:29:07.863529Z","steps":["trace[1895152459] 'process raft request' (duration: 131.201569ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:07.863656Z","caller":"traceutil/trace.go:171","msg":"trace[481740761] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"415.300466ms","start":"2026-05-20T21:29:07.448326Z","end":"2026-05-20T21:29:07.863626Z","steps":["trace[481740761] 'process raft request' (duration: 414.660772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:07.8637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:07.448321Z","time spent":"415.355766ms","remote":"127.0.0.1:50076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:29:07.863732Z","caller":"traceutil/trace.go:171","msg":"trace[2034111113] linearizableReadLoop","detail":"{readStateIndex:4892; appliedIndex:4888; }","duration":"380.489171ms","start":"2026-05-20T21:29:07.483221Z","end":"2026-05-20T21:29:07.86371Z","steps":["trace[2034111113] 'read index received' (duration: 308.129563ms)","trace[2034111113] 'applied index is now lower than readState.Index' (duration: 72.358398ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:29:07.863834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.615624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-20T21:29:07.863856Z","caller":"traceutil/trace.go:171","msg":"trace[1716851371] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4715; }","duration":"380.652144ms","start":"2026-05-20T21:29:07.483197Z","end":"2026-05-20T21:29:07.863849Z","steps":["trace[1716851371] 'agreement among raft nodes before linearized reading' (duration: 380.572162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:07.863869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:07.483186Z","time spent":"380.680414ms","remote":"127.0.0.1:37322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5674,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-20T21:29:07.86402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.956394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0d4d22f4-ff0a-4575-922d-bfae122b4698\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-05-20T21:29:07.864058Z","caller":"traceutil/trace.go:171","msg":"trace[1839488615] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0d4d22f4-ff0a-4575-922d-bfae122b4698; range_end:; response_count:1; response_revision:4715; }","duration":"259.029018ms","start":"2026-05-20T21:29:07.605019Z","end":"2026-05-20T21:29:07.864048Z","steps":["trace[1839488615] 'agreement among raft nodes before linearized reading' (duration: 258.825573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:08.70331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.187688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T21:29:08.703504Z","caller":"traceutil/trace.go:171","msg":"trace[508097023] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"682.908005ms","start":"2026-05-20T21:29:08.020579Z","end":"2026-05-20T21:29:08.703487Z","steps":["trace[508097023] 'process raft request' (duration: 682.828914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:08.703573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:08.020553Z","time spent":"682.991507ms","remote":"127.0.0.1:49974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T21:29:08.703808Z","caller":"traceutil/trace.go:171","msg":"trace[773001713] linearizableReadLoop","detail":"{readStateIndex:4896; appliedIndex:4895; }","duration":"730.205833ms","start":"2026-05-20T21:29:07.973569Z","end":"2026-05-20T21:29:08.703774Z","steps":["trace[773001713] 'read index received' (duration: 13.451644ms)","trace[773001713] 'applied index is now lower than readState.Index' (duration: 716.750559ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:29:08.703904Z","caller":"traceutil/trace.go:171","msg":"trace[1059657381] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"811.516879ms","start":"2026-05-20T21:29:07.89236Z","end":"2026-05-20T21:29:08.703877Z","steps":["trace[1059657381] 'process raft request' (duration: 94.679258ms)","trace[1059657381] 'compare' (duration: 715.603565ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:29:08.704034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:07.892346Z","time spent":"811.614592ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:29:08.704167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.683653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-20T21:29:08.704204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.560072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:29:08.704237Z","caller":"traceutil/trace.go:171","msg":"trace[633542100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4718; }","duration":"576.792357ms","start":"2026-05-20T21:29:08.127428Z","end":"2026-05-20T21:29:08.70422Z","steps":["trace[633542100] 'agreement among raft nodes before linearized reading' (duration: 576.610363ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:08.704246Z","caller":"traceutil/trace.go:171","msg":"trace[1145115123] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4718; }","duration":"576.645473ms","start":"2026-05-20T21:29:08.127588Z","end":"2026-05-20T21:29:08.704234Z","steps":["trace[1145115123] 'agreement among raft nodes before linearized reading' (duration: 576.571873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:08.704258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.833266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-20T21:29:08.704292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:08.127565Z","time spent":"576.717835ms","remote":"127.0.0.1:48726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-20T21:29:08.704294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:08.1274Z","time spent":"576.880899ms","remote":"127.0.0.1:50172","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-05-20T21:29:08.704326Z","caller":"traceutil/trace.go:171","msg":"trace[1164238917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4718; }","duration":"493.96122ms","start":"2026-05-20T21:29:08.210347Z","end":"2026-05-20T21:29:08.704308Z","steps":["trace[1164238917] 'agreement among raft nodes before linearized reading' (duration: 493.762986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:08.704376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:08.210333Z","time spent":"494.034791ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T21:29:08.704383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.83046ms","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-05-20T21:29:08.704417Z","caller":"traceutil/trace.go:171","msg":"trace[1100333042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4718; }","duration":"559.885381ms","start":"2026-05-20T21:29:08.144521Z","end":"2026-05-20T21:29:08.704407Z","steps":["trace[1100333042] 'agreement among raft nodes before linearized reading' (duration: 559.785608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:08.704207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.653343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-05-20T21:29:08.704457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:08.14451Z","time spent":"559.940431ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T21:29:08.704415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.012076ms","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-05-20T21:29:08.704485Z","caller":"traceutil/trace.go:171","msg":"trace[1532783013] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4718; }","duration":"730.932128ms","start":"2026-05-20T21:29:07.97353Z","end":"2026-05-20T21:29:08.704462Z","steps":["trace[1532783013] 'agreement among raft nodes before linearized reading' (duration: 730.417128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:08.704519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.401878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:29:08.704542Z","caller":"traceutil/trace.go:171","msg":"trace[250161639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4718; }","duration":"572.14647ms","start":"2026-05-20T21:29:08.132349Z","end":"2026-05-20T21:29:08.704496Z","steps":["trace[250161639] 'agreement among raft nodes before linearized reading' (duration: 571.839453ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:08.704564Z","caller":"traceutil/trace.go:171","msg":"trace[1902853606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4718; }","duration":"411.481789ms","start":"2026-05-20T21:29:08.293068Z","end":"2026-05-20T21:29:08.70455Z","steps":["trace[1902853606] 'agreement among raft nodes before linearized reading' (duration: 411.413048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:08.704603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:08.293048Z","time spent":"411.544832ms","remote":"127.0.0.1:49898","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-05-20T21:29:08.704555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:07.973513Z","time spent":"731.024031ms","remote":"127.0.0.1:50064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-20T21:29:08.704627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:08.132336Z","time spent":"572.251322ms","remote":"127.0.0.1:50172","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-05-20T21:29:08.704513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.18348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:29:08.704811Z","caller":"traceutil/trace.go:171","msg":"trace[1125296114] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:4718; }","duration":"261.642758ms","start":"2026-05-20T21:29:08.443152Z","end":"2026-05-20T21:29:08.704795Z","steps":["trace[1125296114] 'agreement among raft nodes before linearized reading' (duration: 261.048446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:09.518562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.313837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T21:29:09.518818Z","caller":"traceutil/trace.go:171","msg":"trace[241856525] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"809.50943ms","start":"2026-05-20T21:29:08.709278Z","end":"2026-05-20T21:29:09.518787Z","steps":["trace[241856525] 'process raft request' (duration: 209.887247ms)","trace[241856525] 'compare' (duration: 599.134763ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:29:09.518875Z","caller":"traceutil/trace.go:171","msg":"trace[1596203384] linearizableReadLoop","detail":"{readStateIndex:4901; appliedIndex:4897; }","duration":"648.79895ms","start":"2026-05-20T21:29:08.87006Z","end":"2026-05-20T21:29:09.518859Z","steps":["trace[1596203384] 'read index received' (duration: 49.118127ms)","trace[1596203384] 'applied index is now lower than readState.Index' (duration: 599.679683ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:29:09.518964Z","caller":"traceutil/trace.go:171","msg":"trace[282931971] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"809.146922ms","start":"2026-05-20T21:29:08.709807Z","end":"2026-05-20T21:29:09.518954Z","steps":["trace[282931971] 'process raft request' (duration: 809.01707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:09.518979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:08.709264Z","time spent":"809.630853ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:29:09.518967Z","caller":"traceutil/trace.go:171","msg":"trace[1426183105] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"809.159662ms","start":"2026-05-20T21:29:08.709778Z","end":"2026-05-20T21:29:09.518937Z","steps":["trace[1426183105] 'process raft request' (duration: 809.009179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:09.519181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.124337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0d4d22f4-ff0a-4575-922d-bfae122b4698\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-05-20T21:29:09.519222Z","caller":"traceutil/trace.go:171","msg":"trace[200504918] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0d4d22f4-ff0a-4575-922d-bfae122b4698; range_end:; response_count:1; response_revision:4722; }","duration":"649.192128ms","start":"2026-05-20T21:29:08.870019Z","end":"2026-05-20T21:29:09.519211Z","steps":["trace[200504918] 'agreement among raft nodes before linearized reading' (duration: 649.038555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:09.519253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:08.870002Z","time spent":"649.24439ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0d4d22f4-ff0a-4575-922d-bfae122b4698\" "} {"level":"warn","ts":"2026-05-20T21:29:09.519175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:08.709762Z","time spent":"809.336816ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:29:09.519408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.505689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T21:29:09.519032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:08.709762Z","time spent":"809.236264ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:29:09.519482Z","caller":"traceutil/trace.go:171","msg":"trace[2038080743] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:4722; }","duration":"424.630472ms","start":"2026-05-20T21:29:09.094833Z","end":"2026-05-20T21:29:09.519464Z","steps":["trace[2038080743] 'agreement among raft nodes before linearized reading' (duration: 424.50771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:09.519539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:09.094811Z","time spent":"424.716845ms","remote":"127.0.0.1:48734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-20T21:29:09.519478Z","caller":"traceutil/trace.go:171","msg":"trace[1545605067] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"809.954509ms","start":"2026-05-20T21:29:08.709506Z","end":"2026-05-20T21:29:09.519461Z","steps":["trace[1545605067] 'process raft request' (duration: 809.156433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:09.519623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.042179ms","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-05-20T21:29:09.519663Z","caller":"traceutil/trace.go:171","msg":"trace[923194729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4722; }","duration":"358.10471ms","start":"2026-05-20T21:29:09.161549Z","end":"2026-05-20T21:29:09.519653Z","steps":["trace[923194729] 'agreement among raft nodes before linearized reading' (duration: 358.005127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:09.519698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:09.161536Z","time spent":"358.15527ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T21:29:09.519697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.686924ms","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-05-20T21:29:09.519751Z","caller":"traceutil/trace.go:171","msg":"trace[1788255469] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:4722; }","duration":"379.774018ms","start":"2026-05-20T21:29:09.139961Z","end":"2026-05-20T21:29:09.519735Z","steps":["trace[1788255469] 'agreement among raft nodes before linearized reading' (duration: 379.687126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:09.519759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.736009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T21:29:09.519807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:09.139941Z","time spent":"379.855798ms","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-05-20T21:29:09.519825Z","caller":"traceutil/trace.go:171","msg":"trace[882253555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4722; }","duration":"224.84922ms","start":"2026-05-20T21:29:09.294958Z","end":"2026-05-20T21:29:09.519807Z","steps":["trace[882253555] 'agreement among raft nodes before linearized reading' (duration: 224.749078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:09.519917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:08.709485Z","time spent":"810.101923ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:29:09.822332Z","caller":"traceutil/trace.go:171","msg":"trace[1518561905] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"297.310259ms","start":"2026-05-20T21:29:09.524997Z","end":"2026-05-20T21:29:09.822307Z","steps":["trace[1518561905] 'process raft request' (duration: 253.187308ms)","trace[1518561905] 'compare' (duration: 43.351115ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:29:11.157539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.74554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-20T21:29:11.157589Z","caller":"traceutil/trace.go:171","msg":"trace[223468520] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4739; }","duration":"108.845034ms","start":"2026-05-20T21:29:11.048731Z","end":"2026-05-20T21:29:11.157576Z","steps":["trace[223468520] 'range keys from in-memory index tree' (duration: 108.597278ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:17.203517Z","caller":"traceutil/trace.go:171","msg":"trace[89472164] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"174.750381ms","start":"2026-05-20T21:29:17.028749Z","end":"2026-05-20T21:29:17.2035Z","steps":["trace[89472164] 'process raft request' (duration: 174.604118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:17.521329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.859244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:29:17.521419Z","caller":"traceutil/trace.go:171","msg":"trace[1327259188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4842; }","duration":"224.993448ms","start":"2026-05-20T21:29:17.296403Z","end":"2026-05-20T21:29:17.521397Z","steps":["trace[1327259188] 'range keys from in-memory index tree' (duration: 224.762131ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:17.824406Z","caller":"traceutil/trace.go:171","msg":"trace[1270212512] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"125.478795ms","start":"2026-05-20T21:29:17.698906Z","end":"2026-05-20T21:29:17.824384Z","steps":["trace[1270212512] 'process raft request' (duration: 64.925144ms)","trace[1270212512] 'compare' (duration: 60.449699ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:29:23.406348Z","caller":"traceutil/trace.go:171","msg":"trace[463905543] linearizableReadLoop","detail":"{readStateIndex:5086; appliedIndex:5085; }","duration":"342.148979ms","start":"2026-05-20T21:29:23.064176Z","end":"2026-05-20T21:29:23.406325Z","steps":["trace[463905543] 'read index received' (duration: 341.966715ms)","trace[463905543] 'applied index is now lower than readState.Index' (duration: 181.294µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:29:23.406479Z","caller":"traceutil/trace.go:171","msg":"trace[1191802697] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"660.027381ms","start":"2026-05-20T21:29:22.746418Z","end":"2026-05-20T21:29:23.406445Z","steps":["trace[1191802697] 'process raft request' (duration: 659.744626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:23.406642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:22.746391Z","time spent":"660.164805ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:29:23.406689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.218346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17229"} {"level":"warn","ts":"2026-05-20T21:29:23.406722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.373115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:29:23.406778Z","caller":"traceutil/trace.go:171","msg":"trace[347832446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4900; }","duration":"112.461577ms","start":"2026-05-20T21:29:23.2943Z","end":"2026-05-20T21:29:23.406762Z","steps":["trace[347832446] 'agreement among raft nodes before linearized reading' (duration: 112.384446ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:23.406746Z","caller":"traceutil/trace.go:171","msg":"trace[620743972] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4900; }","duration":"337.321408ms","start":"2026-05-20T21:29:23.069404Z","end":"2026-05-20T21:29:23.406726Z","steps":["trace[620743972] 'agreement among raft nodes before linearized reading' (duration: 337.128694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:23.406891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:23.069386Z","time spent":"337.489191ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":17252,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-20T21:29:23.407074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.491375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-20T21:29:23.407162Z","caller":"traceutil/trace.go:171","msg":"trace[1065825127] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4900; }","duration":"266.630037ms","start":"2026-05-20T21:29:23.140506Z","end":"2026-05-20T21:29:23.407136Z","steps":["trace[1065825127] 'agreement among raft nodes before linearized reading' (duration: 266.2793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:23.406884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.61184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-20T21:29:23.408Z","caller":"traceutil/trace.go:171","msg":"trace[1581392772] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4900; }","duration":"343.843495ms","start":"2026-05-20T21:29:23.064133Z","end":"2026-05-20T21:29:23.407976Z","steps":["trace[1581392772] 'agreement among raft nodes before linearized reading' (duration: 342.446426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:23.408109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:29:23.0641Z","time spent":"343.959346ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-20T21:29:23.532121Z","caller":"traceutil/trace.go:171","msg":"trace[1883207739] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"108.36624ms","start":"2026-05-20T21:29:23.423735Z","end":"2026-05-20T21:29:23.532102Z","steps":["trace[1883207739] 'process raft request' (duration: 102.581399ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:49.831408Z","caller":"traceutil/trace.go:171","msg":"trace[336458511] linearizableReadLoop","detail":"{readStateIndex:5262; appliedIndex:5260; }","duration":"119.288275ms","start":"2026-05-20T21:29:49.712091Z","end":"2026-05-20T21:29:49.831379Z","steps":["trace[336458511] 'read index received' (duration: 44.470853ms)","trace[336458511] 'applied index is now lower than readState.Index' (duration: 74.816022ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:29:49.831497Z","caller":"traceutil/trace.go:171","msg":"trace[1010598410] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"123.622005ms","start":"2026-05-20T21:29:49.707843Z","end":"2026-05-20T21:29:49.831465Z","steps":["trace[1010598410] 'process raft request' (duration: 64.781274ms)","trace[1010598410] 'compare' (duration: 58.612465ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:29:49.831735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.619952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T21:29:49.831787Z","caller":"traceutil/trace.go:171","msg":"trace[607305185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5071; }","duration":"119.702362ms","start":"2026-05-20T21:29:49.712068Z","end":"2026-05-20T21:29:49.83177Z","steps":["trace[607305185] 'agreement among raft nodes before linearized reading' (duration: 119.523599ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:29:50.29909Z","caller":"traceutil/trace.go:171","msg":"trace[1803026893] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"213.909786ms","start":"2026-05-20T21:29:50.085156Z","end":"2026-05-20T21:29:50.299065Z","steps":["trace[1803026893] 'process raft request' (duration: 170.310413ms)","trace[1803026893] 'compare' (duration: 43.164674ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:29:50.299406Z","caller":"traceutil/trace.go:171","msg":"trace[9222703] linearizableReadLoop","detail":"{readStateIndex:5269; appliedIndex:5267; }","duration":"208.973863ms","start":"2026-05-20T21:29:50.090412Z","end":"2026-05-20T21:29:50.299385Z","steps":["trace[9222703] 'read index received' (duration: 39.212163ms)","trace[9222703] 'applied index is now lower than readState.Index' (duration: 169.76081ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:29:50.299621Z","caller":"traceutil/trace.go:171","msg":"trace[1461226718] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"213.568178ms","start":"2026-05-20T21:29:50.086038Z","end":"2026-05-20T21:29:50.299606Z","steps":["trace[1461226718] 'process raft request' (duration: 212.985996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:29:50.299822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.395471ms","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-05-20T21:29:50.299895Z","caller":"traceutil/trace.go:171","msg":"trace[1592228946] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5077; }","duration":"209.529565ms","start":"2026-05-20T21:29:50.090349Z","end":"2026-05-20T21:29:50.299878Z","steps":["trace[1592228946] 'agreement among raft nodes before linearized reading' (duration: 209.318601ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:30:07.489246Z","caller":"traceutil/trace.go:171","msg":"trace[1490371189] linearizableReadLoop","detail":"{readStateIndex:5458; appliedIndex:5457; }","duration":"303.52875ms","start":"2026-05-20T21:30:07.185693Z","end":"2026-05-20T21:30:07.489221Z","steps":["trace[1490371189] 'read index received' (duration: 303.390167ms)","trace[1490371189] 'applied index is now lower than readState.Index' (duration: 137.683µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:30:07.489337Z","caller":"traceutil/trace.go:171","msg":"trace[693896757] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"434.995931ms","start":"2026-05-20T21:30:07.054306Z","end":"2026-05-20T21:30:07.489302Z","steps":["trace[693896757] 'process raft request' (duration: 434.723976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:07.48943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.675033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T21:30:07.489464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.784718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:30:07.489502Z","caller":"traceutil/trace.go:171","msg":"trace[28699082] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5262; }","duration":"303.832685ms","start":"2026-05-20T21:30:07.185652Z","end":"2026-05-20T21:30:07.489485Z","steps":["trace[28699082] 'agreement among raft nodes before linearized reading' (duration: 303.661802ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:30:07.489533Z","caller":"traceutil/trace.go:171","msg":"trace[1325523198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5262; }","duration":"194.880669ms","start":"2026-05-20T21:30:07.294629Z","end":"2026-05-20T21:30:07.489509Z","steps":["trace[1325523198] 'agreement among raft nodes before linearized reading' (duration: 194.786956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:07.489553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:30:07.185635Z","time spent":"303.906948ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-20T21:30:07.489564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.539322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T21:30:07.489577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:30:07.054287Z","time spent":"435.179765ms","remote":"127.0.0.1:34654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:30:07.489688Z","caller":"traceutil/trace.go:171","msg":"trace[1084125153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5262; }","duration":"210.667465ms","start":"2026-05-20T21:30:07.278984Z","end":"2026-05-20T21:30:07.489651Z","steps":["trace[1084125153] 'agreement among raft nodes before linearized reading' (duration: 210.400569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:07.490174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.035627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T21:30:07.490263Z","caller":"traceutil/trace.go:171","msg":"trace[445457112] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:5262; }","duration":"187.14813ms","start":"2026-05-20T21:30:07.303095Z","end":"2026-05-20T21:30:07.490243Z","steps":["trace[445457112] 'agreement among raft nodes before linearized reading' (duration: 186.955906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:07.829996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.176147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T21:30:07.830669Z","caller":"traceutil/trace.go:171","msg":"trace[189439015] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"180.473332ms","start":"2026-05-20T21:30:07.650158Z","end":"2026-05-20T21:30:07.830632Z","steps":["trace[189439015] 'process raft request' (duration: 29.58936ms)","trace[189439015] 'compare' (duration: 149.549144ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:30:08.051915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.93363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T21:30:08.052036Z","caller":"traceutil/trace.go:171","msg":"trace[59851373] linearizableReadLoop","detail":"{readStateIndex:5461; appliedIndex:5460; }","duration":"127.015899ms","start":"2026-05-20T21:30:07.924999Z","end":"2026-05-20T21:30:08.052015Z","steps":["trace[59851373] 'read index received' (duration: 11.810634ms)","trace[59851373] 'applied index is now lower than readState.Index' (duration: 115.203675ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:30:08.052158Z","caller":"traceutil/trace.go:171","msg":"trace[430515960] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"133.295908ms","start":"2026-05-20T21:30:07.918818Z","end":"2026-05-20T21:30:08.052114Z","steps":["trace[430515960] 'process raft request' (duration: 18.067622ms)","trace[430515960] 'compare' (duration: 114.712416ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:30:08.052287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.285195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T21:30:08.052347Z","caller":"traceutil/trace.go:171","msg":"trace[852508905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5265; }","duration":"127.373716ms","start":"2026-05-20T21:30:07.924959Z","end":"2026-05-20T21:30:08.052333Z","steps":["trace[852508905] 'agreement among raft nodes before linearized reading' (duration: 127.165283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:08.052291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.767941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T21:30:08.052453Z","caller":"traceutil/trace.go:171","msg":"trace[1533096718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5265; }","duration":"121.957824ms","start":"2026-05-20T21:30:07.930467Z","end":"2026-05-20T21:30:08.052425Z","steps":["trace[1533096718] 'agreement among raft nodes before linearized reading' (duration: 121.675779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:08.483906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.58517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T21:30:08.484163Z","caller":"traceutil/trace.go:171","msg":"trace[202878652] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"426.412792ms","start":"2026-05-20T21:30:08.057715Z","end":"2026-05-20T21:30:08.484128Z","steps":["trace[202878652] 'process raft request' (duration: 233.527215ms)","trace[202878652] 'compare' (duration: 192.451978ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:30:08.484208Z","caller":"traceutil/trace.go:171","msg":"trace[200817160] linearizableReadLoop","detail":"{readStateIndex:5463; appliedIndex:5461; }","duration":"424.39802ms","start":"2026-05-20T21:30:08.059796Z","end":"2026-05-20T21:30:08.484194Z","steps":["trace[200817160] 'read index received' (duration: 231.421051ms)","trace[200817160] 'applied index is now lower than readState.Index' (duration: 192.976159ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:30:08.484277Z","caller":"traceutil/trace.go:171","msg":"trace[1223771410] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"282.596166ms","start":"2026-05-20T21:30:08.201671Z","end":"2026-05-20T21:30:08.484267Z","steps":["trace[1223771410] 'process raft request' (duration: 282.448194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:08.484311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:30:08.057695Z","time spent":"426.537554ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:30:08.484182Z","caller":"traceutil/trace.go:171","msg":"trace[1929436831] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"426.195196ms","start":"2026-05-20T21:30:08.05797Z","end":"2026-05-20T21:30:08.484165Z","steps":["trace[1929436831] 'process raft request' (duration: 426.063684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:08.484362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.554033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:30:08.484411Z","caller":"traceutil/trace.go:171","msg":"trace[423183657] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5268; }","duration":"424.633805ms","start":"2026-05-20T21:30:08.059764Z","end":"2026-05-20T21:30:08.484398Z","steps":["trace[423183657] 'agreement among raft nodes before linearized reading' (duration: 424.509932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:08.484455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:30:08.059751Z","time spent":"424.696516ms","remote":"127.0.0.1:50146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-05-20T21:30:08.484446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:30:08.05796Z","time spent":"426.425361ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T21:30:08.552694Z","caller":"traceutil/trace.go:171","msg":"trace[1361146362] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"165.429741ms","start":"2026-05-20T21:30:08.38724Z","end":"2026-05-20T21:30:08.55267Z","steps":["trace[1361146362] 'process raft request' (duration: 165.292098ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:30:08.67883Z","caller":"traceutil/trace.go:171","msg":"trace[1296692402] linearizableReadLoop","detail":"{readStateIndex:5467; appliedIndex:5464; }","duration":"194.568502ms","start":"2026-05-20T21:30:08.484238Z","end":"2026-05-20T21:30:08.678807Z","steps":["trace[1296692402] 'read index received' (duration: 68.37034ms)","trace[1296692402] 'applied index is now lower than readState.Index' (duration: 126.197112ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:30:08.678953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.068084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:30:08.678989Z","caller":"traceutil/trace.go:171","msg":"trace[810086264] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5271; }","duration":"619.147935ms","start":"2026-05-20T21:30:08.059832Z","end":"2026-05-20T21:30:08.67898Z","steps":["trace[810086264] 'agreement among raft nodes before linearized reading' (duration: 619.065273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:08.679027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:30:08.059825Z","time spent":"619.193756ms","remote":"127.0.0.1:50146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"info","ts":"2026-05-20T21:30:08.679086Z","caller":"traceutil/trace.go:171","msg":"trace[555325895] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"273.028279ms","start":"2026-05-20T21:30:08.406038Z","end":"2026-05-20T21:30:08.679067Z","steps":["trace[555325895] 'process raft request' (duration: 181.456741ms)","trace[555325895] 'compare' (duration: 91.150719ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:30:08.679062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.794716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-20T21:30:08.679173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.077831ms","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-05-20T21:30:08.679168Z","caller":"traceutil/trace.go:171","msg":"trace[79749509] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5271; }","duration":"503.005491ms","start":"2026-05-20T21:30:08.176145Z","end":"2026-05-20T21:30:08.67915Z","steps":["trace[79749509] 'agreement among raft nodes before linearized reading' (duration: 502.730925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:08.67925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:30:08.176123Z","time spent":"503.114163ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":34,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-20T21:30:08.679307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.519022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"info","ts":"2026-05-20T21:30:08.679347Z","caller":"traceutil/trace.go:171","msg":"trace[241359459] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5271; }","duration":"616.568283ms","start":"2026-05-20T21:30:08.062768Z","end":"2026-05-20T21:30:08.679336Z","steps":["trace[241359459] 'agreement among raft nodes before linearized reading' (duration: 616.44939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:08.679008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.63683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T21:30:08.679379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:30:08.062761Z","time spent":"616.612083ms","remote":"127.0.0.1:50146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6180,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"info","ts":"2026-05-20T21:30:08.67941Z","caller":"traceutil/trace.go:171","msg":"trace[1317924678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5271; }","duration":"385.049389ms","start":"2026-05-20T21:30:08.294347Z","end":"2026-05-20T21:30:08.679397Z","steps":["trace[1317924678] 'agreement among raft nodes before linearized reading' (duration: 384.61057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:08.679455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:30:08.29429Z","time spent":"385.155321ms","remote":"127.0.0.1:49898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-20T21:30:08.67945Z","caller":"traceutil/trace.go:171","msg":"trace[1658881464] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"220.384583ms","start":"2026-05-20T21:30:08.458899Z","end":"2026-05-20T21:30:08.679284Z","steps":["trace[1658881464] 'process raft request' (duration: 219.862002ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:30:08.679233Z","caller":"traceutil/trace.go:171","msg":"trace[1961591624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5271; }","duration":"240.194962ms","start":"2026-05-20T21:30:08.439023Z","end":"2026-05-20T21:30:08.679218Z","steps":["trace[1961591624] 'agreement among raft nodes before linearized reading' (duration: 239.817034ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:30:08.825961Z","caller":"traceutil/trace.go:171","msg":"trace[1256043784] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"141.881086ms","start":"2026-05-20T21:30:08.684061Z","end":"2026-05-20T21:30:08.825942Z","steps":["trace[1256043784] 'process raft request' (duration: 132.775558ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:30:12.477725Z","caller":"traceutil/trace.go:171","msg":"trace[1712072439] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"107.500525ms","start":"2026-05-20T21:30:12.370206Z","end":"2026-05-20T21:30:12.477707Z","steps":["trace[1712072439] 'process raft request' (duration: 107.357421ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:30:19.477584Z","caller":"traceutil/trace.go:171","msg":"trace[1622599071] linearizableReadLoop","detail":"{readStateIndex:5547; appliedIndex:5546; }","duration":"299.345031ms","start":"2026-05-20T21:30:19.17822Z","end":"2026-05-20T21:30:19.477565Z","steps":["trace[1622599071] 'read index received' (duration: 299.225739ms)","trace[1622599071] 'applied index is now lower than readState.Index' (duration: 118.712µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:30:19.477665Z","caller":"traceutil/trace.go:171","msg":"trace[1728567853] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"405.097705ms","start":"2026-05-20T21:30:19.072559Z","end":"2026-05-20T21:30:19.477657Z","steps":["trace[1728567853] 'process raft request' (duration: 404.900701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:19.477736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T21:30:19.072539Z","time spent":"405.141786ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T21:30:19.477797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.897898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T21:30:19.477853Z","caller":"traceutil/trace.go:171","msg":"trace[1496524573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5348; }","duration":"182.98553ms","start":"2026-05-20T21:30:19.294857Z","end":"2026-05-20T21:30:19.477843Z","steps":["trace[1496524573] 'agreement among raft nodes before linearized reading' (duration: 182.914798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:19.477991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.76511ms","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-05-20T21:30:19.478018Z","caller":"traceutil/trace.go:171","msg":"trace[701895908] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5348; }","duration":"299.852872ms","start":"2026-05-20T21:30:19.178158Z","end":"2026-05-20T21:30:19.478011Z","steps":["trace[701895908] 'agreement among raft nodes before linearized reading' (duration: 299.787431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T21:30:19.8191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.677164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-20T21:30:19.819178Z","caller":"traceutil/trace.go:171","msg":"trace[2022743547] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5348; }","duration":"274.785876ms","start":"2026-05-20T21:30:19.544372Z","end":"2026-05-20T21:30:19.819158Z","steps":["trace[2022743547] 'range keys from in-memory index tree' (duration: 274.513172ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:30:19.969602Z","caller":"traceutil/trace.go:171","msg":"trace[529215775] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"102.627269ms","start":"2026-05-20T21:30:19.86695Z","end":"2026-05-20T21:30:19.969577Z","steps":["trace[529215775] 'process raft request' (duration: 102.440485ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:30:51.851325Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2909} {"level":"info","ts":"2026-05-20T21:30:51.930571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2909,"took":"78.181184ms","hash":825604844,"current-db-size-bytes":52736000,"current-db-size":"53 MB","current-db-size-in-use-bytes":29343744,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-20T21:30:51.93064Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":825604844,"revision":2909,"compact-revision":-1} {"level":"info","ts":"2026-05-20T21:33:11.910704Z","caller":"traceutil/trace.go:171","msg":"trace[990358215] transaction","detail":"{read_only:false; response_revision:6535; number_of_response:1; }","duration":"108.142607ms","start":"2026-05-20T21:33:11.802534Z","end":"2026-05-20T21:33:11.910677Z","steps":["trace[990358215] 'process raft request' (duration: 108.004082ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:33:23.702298Z","caller":"traceutil/trace.go:171","msg":"trace[2130702500] transaction","detail":"{read_only:false; response_revision:6647; number_of_response:1; }","duration":"110.734463ms","start":"2026-05-20T21:33:23.591541Z","end":"2026-05-20T21:33:23.702276Z","steps":["trace[2130702500] 'process raft request' (duration: 15.020109ms)","trace[2130702500] 'compare' (duration: 95.053172ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T21:33:32.258707Z","caller":"traceutil/trace.go:171","msg":"trace[1724554140] transaction","detail":"{read_only:false; response_revision:6692; number_of_response:1; }","duration":"147.972363ms","start":"2026-05-20T21:33:32.110695Z","end":"2026-05-20T21:33:32.258667Z","steps":["trace[1724554140] 'process raft request' (duration: 86.191062ms)","trace[1724554140] 'compare' (duration: 61.632885ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T21:33:32.473626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.662662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com\" ","response":"range_response_count:1 size:5746"} {"level":"info","ts":"2026-05-20T21:33:32.473715Z","caller":"traceutil/trace.go:171","msg":"trace[385224048] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com; range_end:; response_count:1; response_revision:6692; }","duration":"101.762614ms","start":"2026-05-20T21:33:32.371934Z","end":"2026-05-20T21:33:32.473696Z","steps":["trace[385224048] 'range keys from in-memory index tree' (duration: 101.507556ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:33:43.151321Z","caller":"traceutil/trace.go:171","msg":"trace[482104985] transaction","detail":"{read_only:false; response_revision:6744; number_of_response:1; }","duration":"120.65211ms","start":"2026-05-20T21:33:43.030643Z","end":"2026-05-20T21:33:43.151295Z","steps":["trace[482104985] 'process raft request' (duration: 120.491074ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T21:35:51.855826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5589} {"level":"info","ts":"2026-05-20T21:35:51.958108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5589,"took":"101.073981ms","hash":251858063,"current-db-size-bytes":52736000,"current-db-size":"53 MB","current-db-size-in-use-bytes":18370560,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-20T21:35:51.95817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":251858063,"revision":5589,"compact-revision":2909}