{"level":"warn","ts":"2026-05-19T06:01:34.089862Z","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-19T06:01:34.089992Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-19T06:01:34.090098Z","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-19T06:01:34.090115Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-05-19T06:01:34.090159Z","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-19T06:01:34.090895Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-05-19T06:01:34.090982Z","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":16,"max-cpu-available":16,"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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-19T06:01:34.51814Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"426.885161ms"} {"level":"info","ts":"2026-05-19T06:01:34.580704Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-05-19T06:01:34.581898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T06:01:34.58202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-05-19T06:01:34.582111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T06:01:34.582375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-05-19T06:01:34.582603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-05-19T06:01:34.593729Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T06:01:34.595367Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T06:01:34.59645Z","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-19T06:01:34.599074Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T06:01:34.599503Z","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-19T06:01:34.599574Z","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-19T06:01:34.599584Z","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-19T06:01:34.599551Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T06:01:34.601202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-05-19T06:01:34.601319Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-05-19T06:01:34.602669Z","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-19T06:01:34.602725Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-05-19T06:01:34.602771Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-05-19T06:01:34.602984Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T06:01:34.603026Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T06:01:34.884066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T06:01:34.884142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T06:01:34.8842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-05-19T06:01:34.88422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-05-19T06:01:34.884231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-05-19T06:01:34.884273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-05-19T06:01:34.884286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-05-19T06:01:34.886287Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T06:01:34.887008Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T06:01:34.887007Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T06:01:34.887055Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T06:01:34.887357Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T06:01:34.88746Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T06:01:34.888881Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-05-19T06:01:34.888909Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T06:01:34.889015Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T06:01:34.889115Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T06:01:34.891461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-19T06:01:41.353258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.618628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-19T06:01:41.353405Z","caller":"traceutil/trace.go:171","msg":"trace[1272162513] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:324; }","duration":"179.845593ms","start":"2026-05-19T06:01:41.173537Z","end":"2026-05-19T06:01:41.353383Z","steps":["trace[1272162513] 'range keys from in-memory index tree' (duration: 178.835967ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:41.485829Z","caller":"traceutil/trace.go:171","msg":"trace[74509379] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"112.198375ms","start":"2026-05-19T06:01:41.373601Z","end":"2026-05-19T06:01:41.4858Z","steps":["trace[74509379] 'process raft request' (duration: 112.012631ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:50.089492Z","caller":"traceutil/trace.go:171","msg":"trace[762938014] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"143.127348ms","start":"2026-05-19T06:01:49.946342Z","end":"2026-05-19T06:01:50.08947Z","steps":["trace[762938014] 'read index received' (duration: 142.930713ms)","trace[762938014] 'applied index is now lower than readState.Index' (duration: 195.955µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:01:50.089602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.254502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:01:50.089633Z","caller":"traceutil/trace.go:171","msg":"trace[998065304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"143.344874ms","start":"2026-05-19T06:01:49.946276Z","end":"2026-05-19T06:01:50.089621Z","steps":["trace[998065304] 'agreement among raft nodes before linearized reading' (duration: 143.281892ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:50.089635Z","caller":"traceutil/trace.go:171","msg":"trace[1812058577] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"165.401803ms","start":"2026-05-19T06:01:49.924178Z","end":"2026-05-19T06:01:50.08958Z","steps":["trace[1812058577] 'process raft request' (duration: 165.168736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:52.357614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.759158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T06:01:52.357756Z","caller":"traceutil/trace.go:171","msg":"trace[1158177886] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"351.772274ms","start":"2026-05-19T06:01:52.005964Z","end":"2026-05-19T06:01:52.357736Z","steps":["trace[1158177886] 'process raft request' (duration: 196.327739ms)","trace[1158177886] 'compare' (duration: 154.602234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:01:52.35782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:52.005942Z","time spent":"351.851276ms","remote":"127.0.0.1:50790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T06:01:52.397338Z","caller":"traceutil/trace.go:171","msg":"trace[407315133] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:427; }","duration":"304.101516ms","start":"2026-05-19T06:01:52.093215Z","end":"2026-05-19T06:01:52.397316Z","steps":["trace[407315133] 'read index received' (duration: 109.304667ms)","trace[407315133] 'applied index is now lower than readState.Index' (duration: 194.795949ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:01:52.397395Z","caller":"traceutil/trace.go:171","msg":"trace[929309493] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"389.958019ms","start":"2026-05-19T06:01:52.007388Z","end":"2026-05-19T06:01:52.397346Z","steps":["trace[929309493] 'process raft request' (duration: 389.779784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:52.397515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.292861ms","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-19T06:01:52.397562Z","caller":"traceutil/trace.go:171","msg":"trace[567615365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"304.390144ms","start":"2026-05-19T06:01:52.093161Z","end":"2026-05-19T06:01:52.397552Z","steps":["trace[567615365] 'agreement among raft nodes before linearized reading' (duration: 304.298341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:52.397617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:52.09314Z","time spent":"304.466266ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T06:01:52.397556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:52.007358Z","time spent":"390.126213ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T06:01:52.397517Z","caller":"traceutil/trace.go:171","msg":"trace[746825776] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"388.147412ms","start":"2026-05-19T06:01:52.009286Z","end":"2026-05-19T06:01:52.397433Z","steps":["trace[746825776] 'process raft request' (duration: 387.977018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:52.398387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:52.009267Z","time spent":"388.565302ms","remote":"127.0.0.1:51150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T06:01:52.574217Z","caller":"traceutil/trace.go:171","msg":"trace[137284791] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"171.740455ms","start":"2026-05-19T06:01:52.402421Z","end":"2026-05-19T06:01:52.574162Z","steps":["trace[137284791] 'process raft request' (duration: 171.1312ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:52.574413Z","caller":"traceutil/trace.go:171","msg":"trace[787909903] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:431; }","duration":"112.412637ms","start":"2026-05-19T06:01:52.461989Z","end":"2026-05-19T06:01:52.574402Z","steps":["trace[787909903] 'read index received' (duration: 111.621547ms)","trace[787909903] 'applied index is now lower than readState.Index' (duration: 788.99µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:01:52.574491Z","caller":"traceutil/trace.go:171","msg":"trace[162848863] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"167.038424ms","start":"2026-05-19T06:01:52.407439Z","end":"2026-05-19T06:01:52.574477Z","steps":["trace[162848863] 'process raft request' (duration: 166.795628ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:52.574236Z","caller":"traceutil/trace.go:171","msg":"trace[724148467] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"170.583556ms","start":"2026-05-19T06:01:52.403624Z","end":"2026-05-19T06:01:52.574208Z","steps":["trace[724148467] 'process raft request' (duration: 170.454472ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:52.574297Z","caller":"traceutil/trace.go:171","msg":"trace[1344657967] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"168.477481ms","start":"2026-05-19T06:01:52.405814Z","end":"2026-05-19T06:01:52.574291Z","steps":["trace[1344657967] 'process raft request' (duration: 168.361379ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:52.574319Z","caller":"traceutil/trace.go:171","msg":"trace[662591402] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"169.380284ms","start":"2026-05-19T06:01:52.404912Z","end":"2026-05-19T06:01:52.574292Z","steps":["trace[662591402] 'process raft request' (duration: 169.203899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:52.575031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.024843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T06:01:52.575113Z","caller":"traceutil/trace.go:171","msg":"trace[901185978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"113.164186ms","start":"2026-05-19T06:01:52.46193Z","end":"2026-05-19T06:01:52.575095Z","steps":["trace[901185978] 'agreement among raft nodes before linearized reading' (duration: 113.040613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:52.882324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.264042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T06:01:52.882645Z","caller":"traceutil/trace.go:171","msg":"trace[555735754] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:437; }","duration":"298.091471ms","start":"2026-05-19T06:01:52.584535Z","end":"2026-05-19T06:01:52.882627Z","steps":["trace[555735754] 'read index received' (duration: 187.205354ms)","trace[555735754] 'applied index is now lower than readState.Index' (duration: 110.884327ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:01:52.882717Z","caller":"traceutil/trace.go:171","msg":"trace[250123262] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"300.135414ms","start":"2026-05-19T06:01:52.582526Z","end":"2026-05-19T06:01:52.882661Z","steps":["trace[250123262] 'process raft request' (duration: 299.911219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:52.882828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.758453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-05-19T06:01:52.882831Z","caller":"traceutil/trace.go:171","msg":"trace[360196473] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"303.754267ms","start":"2026-05-19T06:01:52.579014Z","end":"2026-05-19T06:01:52.882768Z","steps":["trace[360196473] 'process raft request' (duration: 192.977382ms)","trace[360196473] 'compare' (duration: 110.121838ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:01:52.882925Z","caller":"traceutil/trace.go:171","msg":"trace[1171156289] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:427; }","duration":"299.889687ms","start":"2026-05-19T06:01:52.583011Z","end":"2026-05-19T06:01:52.882901Z","steps":["trace[1171156289] 'agreement among raft nodes before linearized reading' (duration: 299.678042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:52.882943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:52.582503Z","time spent":"300.325818ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:01:52.882922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.436301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"warn","ts":"2026-05-19T06:01:52.883011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:52.578991Z","time spent":"303.942113ms","remote":"127.0.0.1:50790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T06:01:52.883042Z","caller":"traceutil/trace.go:171","msg":"trace[1607492639] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:427; }","duration":"300.584716ms","start":"2026-05-19T06:01:52.582445Z","end":"2026-05-19T06:01:52.883029Z","steps":["trace[1607492639] 'agreement among raft nodes before linearized reading' (duration: 300.273877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:52.883247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:52.58243Z","time spent":"300.74321ms","remote":"127.0.0.1:51150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4218,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-05-19T06:01:52.950555Z","caller":"traceutil/trace.go:171","msg":"trace[794552057] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"358.60783ms","start":"2026-05-19T06:01:52.591924Z","end":"2026-05-19T06:01:52.950532Z","steps":["trace[794552057] 'process raft request' (duration: 358.506188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:52.950699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:52.591902Z","time spent":"358.721774ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4013,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T06:01:53.080014Z","caller":"traceutil/trace.go:171","msg":"trace[2123318853] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"189.200225ms","start":"2026-05-19T06:01:52.890781Z","end":"2026-05-19T06:01:53.079982Z","steps":["trace[2123318853] 'process raft request' (duration: 142.307547ms)","trace[2123318853] 'compare' (duration: 46.744174ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:01:53.091115Z","caller":"traceutil/trace.go:171","msg":"trace[14373917] transaction","detail":"{read_only:false; number_of_response:1; response_revision:429; }","duration":"138.694123ms","start":"2026-05-19T06:01:52.952399Z","end":"2026-05-19T06:01:53.091093Z","steps":["trace[14373917] 'process raft request' (duration: 138.472188ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:53.091253Z","caller":"traceutil/trace.go:171","msg":"trace[1774799823] transaction","detail":"{read_only:false; number_of_response:1; response_revision:430; }","duration":"132.725811ms","start":"2026-05-19T06:01:52.958498Z","end":"2026-05-19T06:01:53.091224Z","steps":["trace[1774799823] 'process raft request' (duration: 132.564277ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:53.091407Z","caller":"traceutil/trace.go:171","msg":"trace[990816624] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"136.067975ms","start":"2026-05-19T06:01:52.95514Z","end":"2026-05-19T06:01:53.091208Z","steps":["trace[990816624] 'process raft request' (duration: 135.85295ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:53.392356Z","caller":"traceutil/trace.go:171","msg":"trace[718351190] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"305.129552ms","start":"2026-05-19T06:01:53.087207Z","end":"2026-05-19T06:01:53.392336Z","steps":["trace[718351190] 'process raft request' (duration: 305.078861ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:53.392416Z","caller":"traceutil/trace.go:171","msg":"trace[1635658683] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"307.911844ms","start":"2026-05-19T06:01:53.084477Z","end":"2026-05-19T06:01:53.392389Z","steps":["trace[1635658683] 'process raft request' (duration: 301.345634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:53.392488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:53.087173Z","time spent":"305.241375ms","remote":"127.0.0.1:51150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:01:53.392558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:53.084463Z","time spent":"308.023497ms","remote":"127.0.0.1:50790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T06:01:53.445854Z","caller":"traceutil/trace.go:171","msg":"trace[1023259441] transaction","detail":"{read_only:false; number_of_response:1; response_revision:433; }","duration":"353.577091ms","start":"2026-05-19T06:01:53.092213Z","end":"2026-05-19T06:01:53.44579Z","steps":["trace[1023259441] 'process raft request' (duration: 353.397046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:53.446009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:53.092186Z","time spent":"353.723855ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-05-19T06:01:53.446167Z","caller":"traceutil/trace.go:171","msg":"trace[376061368] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"349.670639ms","start":"2026-05-19T06:01:53.096461Z","end":"2026-05-19T06:01:53.446132Z","steps":["trace[376061368] 'process raft request' (duration: 349.277199ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:53.446236Z","caller":"traceutil/trace.go:171","msg":"trace[2065569647] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"348.802658ms","start":"2026-05-19T06:01:53.097415Z","end":"2026-05-19T06:01:53.446218Z","steps":["trace[2065569647] 'process raft request' (duration: 348.600813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:53.446316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:53.097398Z","time spent":"348.881469ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T06:01:53.446344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:53.096432Z","time spent":"349.811174ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T06:01:53.513393Z","caller":"traceutil/trace.go:171","msg":"trace[1447227861] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:444; }","duration":"355.175772ms","start":"2026-05-19T06:01:53.158185Z","end":"2026-05-19T06:01:53.513361Z","steps":["trace[1447227861] 'read index received' (duration: 227.647346ms)","trace[1447227861] 'applied index is now lower than readState.Index' (duration: 127.525126ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:01:53.513685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.447658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-05-19T06:01:53.51382Z","caller":"traceutil/trace.go:171","msg":"trace[453282729] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:436; }","duration":"355.615534ms","start":"2026-05-19T06:01:53.158152Z","end":"2026-05-19T06:01:53.513768Z","steps":["trace[453282729] 'agreement among raft nodes before linearized reading' (duration: 355.295685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:53.513867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:53.158142Z","time spent":"355.714816ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6984,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-05-19T06:01:53.561957Z","caller":"traceutil/trace.go:171","msg":"trace[1779893556] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"109.44021ms","start":"2026-05-19T06:01:53.452488Z","end":"2026-05-19T06:01:53.561928Z","steps":["trace[1779893556] 'process raft request' (duration: 109.190074ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:53.562257Z","caller":"traceutil/trace.go:171","msg":"trace[1961471158] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"108.67744ms","start":"2026-05-19T06:01:53.45355Z","end":"2026-05-19T06:01:53.562227Z","steps":["trace[1961471158] 'process raft request' (duration: 108.323381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:53.562385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.496834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:01:53.562469Z","caller":"traceutil/trace.go:171","msg":"trace[1840324651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:440; }","duration":"182.627636ms","start":"2026-05-19T06:01:53.379824Z","end":"2026-05-19T06:01:53.562451Z","steps":["trace[1840324651] 'agreement among raft nodes before linearized reading' (duration: 182.490052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:53.562487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.854435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4172"} {"level":"info","ts":"2026-05-19T06:01:53.562539Z","caller":"traceutil/trace.go:171","msg":"trace[1410719536] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:440; }","duration":"160.940678ms","start":"2026-05-19T06:01:53.401584Z","end":"2026-05-19T06:01:53.562525Z","steps":["trace[1410719536] 'agreement among raft nodes before linearized reading' (duration: 160.837865ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:53.562658Z","caller":"traceutil/trace.go:171","msg":"trace[630064119] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"108.668311ms","start":"2026-05-19T06:01:53.453934Z","end":"2026-05-19T06:01:53.562603Z","steps":["trace[630064119] 'process raft request' (duration: 108.174928ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:53.563003Z","caller":"traceutil/trace.go:171","msg":"trace[1612839044] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"107.10152ms","start":"2026-05-19T06:01:53.455881Z","end":"2026-05-19T06:01:53.562983Z","steps":["trace[1612839044] 'process raft request' (duration: 106.34545ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:53.75861Z","caller":"traceutil/trace.go:171","msg":"trace[1020105741] linearizableReadLoop","detail":"{readStateIndex:457; appliedIndex:455; }","duration":"194.85049ms","start":"2026-05-19T06:01:53.563736Z","end":"2026-05-19T06:01:53.758586Z","steps":["trace[1020105741] 'read index received' (duration: 123.391449ms)","trace[1020105741] 'applied index is now lower than readState.Index' (duration: 71.458041ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:01:53.758764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.008085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:01:53.758799Z","caller":"traceutil/trace.go:171","msg":"trace[1357634025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:442; }","duration":"195.091937ms","start":"2026-05-19T06:01:53.563697Z","end":"2026-05-19T06:01:53.758789Z","steps":["trace[1357634025] 'agreement among raft nodes before linearized reading' (duration: 195.007114ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:53.758801Z","caller":"traceutil/trace.go:171","msg":"trace[1125769343] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"234.508122ms","start":"2026-05-19T06:01:53.524241Z","end":"2026-05-19T06:01:53.758749Z","steps":["trace[1125769343] 'process raft request' (duration: 234.270216ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:53.758812Z","caller":"traceutil/trace.go:171","msg":"trace[1304043457] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"240.624939ms","start":"2026-05-19T06:01:53.517996Z","end":"2026-05-19T06:01:53.758621Z","steps":["trace[1304043457] 'process raft request' (duration: 168.993534ms)","trace[1304043457] 'compare' (duration: 71.322648ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:01:53.844205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.572331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-05-19T06:01:53.844289Z","caller":"traceutil/trace.go:171","msg":"trace[1821108905] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:443; }","duration":"275.700014ms","start":"2026-05-19T06:01:53.568571Z","end":"2026-05-19T06:01:53.844271Z","steps":["trace[1821108905] 'agreement among raft nodes before linearized reading' (duration: 275.56513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:53.844224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.562271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-05-19T06:01:53.844424Z","caller":"traceutil/trace.go:171","msg":"trace[1579977863] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:443; }","duration":"275.789426ms","start":"2026-05-19T06:01:53.568616Z","end":"2026-05-19T06:01:53.844406Z","steps":["trace[1579977863] 'agreement among raft nodes before linearized reading' (duration: 275.486328ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:53.844626Z","caller":"traceutil/trace.go:171","msg":"trace[1052915464] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"274.34916ms","start":"2026-05-19T06:01:53.570265Z","end":"2026-05-19T06:01:53.844614Z","steps":["trace[1052915464] 'process raft request' (duration: 273.671042ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:54.07067Z","caller":"traceutil/trace.go:171","msg":"trace[1289999572] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"216.811417ms","start":"2026-05-19T06:01:53.853825Z","end":"2026-05-19T06:01:54.070636Z","steps":["trace[1289999572] 'process raft request' (duration: 181.274751ms)","trace[1289999572] 'compare' (duration: 35.394803ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:01:54.073655Z","caller":"traceutil/trace.go:171","msg":"trace[751482084] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"219.168238ms","start":"2026-05-19T06:01:53.854468Z","end":"2026-05-19T06:01:54.073636Z","steps":["trace[751482084] 'process raft request' (duration: 218.83436ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:54.074113Z","caller":"traceutil/trace.go:171","msg":"trace[1277156794] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"219.165407ms","start":"2026-05-19T06:01:53.854925Z","end":"2026-05-19T06:01:54.07409Z","steps":["trace[1277156794] 'process raft request' (duration: 218.648413ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:54.075631Z","caller":"traceutil/trace.go:171","msg":"trace[77037318] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"218.958952ms","start":"2026-05-19T06:01:53.856654Z","end":"2026-05-19T06:01:54.075613Z","steps":["trace[77037318] 'process raft request' (duration: 218.86252ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:01:56.044234Z","caller":"traceutil/trace.go:171","msg":"trace[745984203] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"590.093535ms","start":"2026-05-19T06:01:55.454117Z","end":"2026-05-19T06:01:56.04421Z","steps":["trace[745984203] 'process raft request' (duration: 589.936412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:01:56.044397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:01:55.45408Z","time spent":"590.236609ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T06:02:04.658976Z","caller":"traceutil/trace.go:171","msg":"trace[1706318123] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"168.290326ms","start":"2026-05-19T06:02:04.490655Z","end":"2026-05-19T06:02:04.658945Z","steps":["trace[1706318123] 'process raft request' (duration: 168.053731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:07.166053Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859042661,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T06:02:07.666358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859042661,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T06:02:07.816432Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.18403099s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T06:02:07.817031Z","caller":"traceutil/trace.go:171","msg":"trace[99960771] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.184716178s","start":"2026-05-19T06:02:06.632291Z","end":"2026-05-19T06:02:07.817007Z","steps":["trace[99960771] 'process raft request' (duration: 1.18437618s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:07.81725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.632267Z","time spent":"1.184864111s","remote":"127.0.0.1:50774","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-19T06:02:07.824016Z","caller":"traceutil/trace.go:171","msg":"trace[1888422458] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.139460292s","start":"2026-05-19T06:02:06.684522Z","end":"2026-05-19T06:02:07.823982Z","steps":["trace[1888422458] 'process raft request' (duration: 1.139189005s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:07.82416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.684504Z","time spent":"1.139580984s","remote":"127.0.0.1:50774","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-19T06:02:07.824137Z","caller":"traceutil/trace.go:171","msg":"trace[1058100548] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.171030886s","start":"2026-05-19T06:02:06.65303Z","end":"2026-05-19T06:02:07.824061Z","steps":["trace[1058100548] 'process raft request' (duration: 1.170488062s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:07.824237Z","caller":"traceutil/trace.go:171","msg":"trace[1334608407] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:526; }","duration":"1.158576665s","start":"2026-05-19T06:02:06.665645Z","end":"2026-05-19T06:02:07.824222Z","steps":["trace[1334608407] 'read index received' (duration: 1.151091663s)","trace[1334608407] 'applied index is now lower than readState.Index' (duration: 7.484142ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:02:07.824282Z","caller":"traceutil/trace.go:171","msg":"trace[402642938] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.126519618s","start":"2026-05-19T06:02:06.697751Z","end":"2026-05-19T06:02:07.824271Z","steps":["trace[402642938] 'process raft request' (duration: 1.126127548s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:07.824316Z","caller":"traceutil/trace.go:171","msg":"trace[317417591] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.100789315s","start":"2026-05-19T06:02:06.723519Z","end":"2026-05-19T06:02:07.824308Z","steps":["trace[317417591] 'process raft request' (duration: 1.100406536s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:07.824331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.697728Z","time spent":"1.12657972s","remote":"127.0.0.1:50774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T06:02:07.824362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.653018Z","time spent":"1.17123509s","remote":"127.0.0.1:50774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T06:02:07.82439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.723478Z","time spent":"1.100866738s","remote":"127.0.0.1:50774","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-19T06:02:07.824404Z","caller":"traceutil/trace.go:171","msg":"trace[1954328111] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.139872733s","start":"2026-05-19T06:02:06.684522Z","end":"2026-05-19T06:02:07.824394Z","steps":["trace[1954328111] 'process raft request' (duration: 1.139221296s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:07.824444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.684496Z","time spent":"1.139930305s","remote":"127.0.0.1:50774","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-19T06:02:07.824488Z","caller":"traceutil/trace.go:171","msg":"trace[344177284] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"861.458608ms","start":"2026-05-19T06:02:06.962997Z","end":"2026-05-19T06:02:07.824455Z","steps":["trace[344177284] 'process raft request' (duration: 861.179961ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:07.824531Z","caller":"traceutil/trace.go:171","msg":"trace[1827633813] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.133824287s","start":"2026-05-19T06:02:06.690693Z","end":"2026-05-19T06:02:07.824517Z","steps":["trace[1827633813] 'process raft request' (duration: 1.133131889s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:07.824534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158881993s","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-19T06:02:07.824555Z","caller":"traceutil/trace.go:171","msg":"trace[1578628055] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.173249403s","start":"2026-05-19T06:02:06.651293Z","end":"2026-05-19T06:02:07.824542Z","steps":["trace[1578628055] 'process raft request' (duration: 1.172161784s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:07.824565Z","caller":"traceutil/trace.go:171","msg":"trace[1822093345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"1.158952595s","start":"2026-05-19T06:02:06.665604Z","end":"2026-05-19T06:02:07.824557Z","steps":["trace[1822093345] 'agreement among raft nodes before linearized reading' (duration: 1.158866023s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:07.824572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.690678Z","time spent":"1.133873998s","remote":"127.0.0.1:50774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T06:02:07.824592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.665587Z","time spent":"1.158999276s","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T06:02:07.824602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.962966Z","time spent":"861.577062ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:02:07.82461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.651271Z","time spent":"1.173312005s","remote":"127.0.0.1:50774","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-19T06:02:07.824675Z","caller":"traceutil/trace.go:171","msg":"trace[1055131634] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.140045236s","start":"2026-05-19T06:02:06.684616Z","end":"2026-05-19T06:02:07.824661Z","steps":["trace[1055131634] 'process raft request' (duration: 1.139153644s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:07.824587Z","caller":"traceutil/trace.go:171","msg":"trace[93843109] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.139876622s","start":"2026-05-19T06:02:06.684643Z","end":"2026-05-19T06:02:07.82452Z","steps":["trace[93843109] 'process raft request' (duration: 1.139152913s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:07.824685Z","caller":"traceutil/trace.go:171","msg":"trace[445081499] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.015659442s","start":"2026-05-19T06:02:06.808949Z","end":"2026-05-19T06:02:07.824609Z","steps":["trace[445081499] 'process raft request' (duration: 1.015173869s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:07.824815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.684608Z","time spent":"1.140119558s","remote":"127.0.0.1:50774","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-19T06:02:07.824636Z","caller":"traceutil/trace.go:171","msg":"trace[1574507947] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.140628022s","start":"2026-05-19T06:02:06.683699Z","end":"2026-05-19T06:02:07.824327Z","steps":["trace[1574507947] 'process raft request' (duration: 1.139889162s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:07.824939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.684603Z","time spent":"1.140151009s","remote":"127.0.0.1:50774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T06:02:07.8249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.808905Z","time spent":"1.015893008s","remote":"127.0.0.1:50774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T06:02:07.825239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.683672Z","time spent":"1.141315359s","remote":"127.0.0.1:50774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T06:02:07.826254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139960894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T06:02:07.826312Z","caller":"traceutil/trace.go:171","msg":"trace[1038108791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"1.140043416s","start":"2026-05-19T06:02:06.686247Z","end":"2026-05-19T06:02:07.826291Z","steps":["trace[1038108791] 'agreement among raft nodes before linearized reading' (duration: 1.139910203s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:07.826339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.686237Z","time spent":"1.140096727s","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T06:02:07.82658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.134655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T06:02:07.826635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.273766ms","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-19T06:02:07.826663Z","caller":"traceutil/trace.go:171","msg":"trace[1548532152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"884.340458ms","start":"2026-05-19T06:02:06.942314Z","end":"2026-05-19T06:02:07.826655Z","steps":["trace[1548532152] 'agreement among raft nodes before linearized reading' (duration: 884.275286ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:07.82666Z","caller":"traceutil/trace.go:171","msg":"trace[147769172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"444.279679ms","start":"2026-05-19T06:02:07.382355Z","end":"2026-05-19T06:02:07.826635Z","steps":["trace[147769172] 'agreement among raft nodes before linearized reading' (duration: 444.153816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:07.826687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:06.942292Z","time spent":"884.389279ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T06:02:07.826708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:07.38233Z","time spent":"444.369201ms","remote":"127.0.0.1:50716","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-19T06:02:10.212903Z","caller":"traceutil/trace.go:171","msg":"trace[821137917] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"101.629139ms","start":"2026-05-19T06:02:10.111257Z","end":"2026-05-19T06:02:10.212886Z","steps":["trace[821137917] 'process raft request' (duration: 101.528896ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:12.351459Z","caller":"traceutil/trace.go:171","msg":"trace[1610492803] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"129.798215ms","start":"2026-05-19T06:02:12.221634Z","end":"2026-05-19T06:02:12.351432Z","steps":["trace[1610492803] 'process raft request' (duration: 101.596728ms)","trace[1610492803] 'compare' (duration: 28.097794ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:02:12.849555Z","caller":"traceutil/trace.go:171","msg":"trace[1880542282] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"286.733749ms","start":"2026-05-19T06:02:12.562798Z","end":"2026-05-19T06:02:12.849532Z","steps":["trace[1880542282] 'process raft request' (duration: 286.205126ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:13.057445Z","caller":"traceutil/trace.go:171","msg":"trace[766971696] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"147.034808ms","start":"2026-05-19T06:02:12.910339Z","end":"2026-05-19T06:02:13.057374Z","steps":["trace[766971696] 'process raft request' (duration: 70.813924ms)","trace[766971696] 'compare' (duration: 75.906106ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:02:14.507236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.195401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.507357Z","caller":"traceutil/trace.go:171","msg":"trace[1439437462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"119.358115ms","start":"2026-05-19T06:02:14.387971Z","end":"2026-05-19T06:02:14.507329Z","steps":["trace[1439437462] 'range keys from in-memory index tree' (duration: 119.12142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.508003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.497831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-19T06:02:14.508091Z","caller":"traceutil/trace.go:171","msg":"trace[1174553995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"147.783978ms","start":"2026-05-19T06:02:14.360288Z","end":"2026-05-19T06:02:14.508072Z","steps":["trace[1174553995] 'range keys from in-memory index tree' (duration: 147.278405ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:14.723799Z","caller":"traceutil/trace.go:171","msg":"trace[856982230] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"214.757583ms","start":"2026-05-19T06:02:14.50893Z","end":"2026-05-19T06:02:14.723688Z","steps":["trace[856982230] 'read index received' (duration: 213.202453ms)","trace[856982230] 'applied index is now lower than readState.Index' (duration: 1.55296ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:02:14.724114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.137954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.72443Z","caller":"traceutil/trace.go:171","msg":"trace[1446847549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"215.486022ms","start":"2026-05-19T06:02:14.508888Z","end":"2026-05-19T06:02:14.724374Z","steps":["trace[1446847549] 'agreement among raft nodes before linearized reading' (duration: 214.958239ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:14.723834Z","caller":"traceutil/trace.go:171","msg":"trace[288183898] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"218.644575ms","start":"2026-05-19T06:02:14.50513Z","end":"2026-05-19T06:02:14.723775Z","steps":["trace[288183898] 'process raft request' (duration: 217.222188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.79895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.637417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.799139Z","caller":"traceutil/trace.go:171","msg":"trace[2027725512] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:621; }","duration":"288.858033ms","start":"2026-05-19T06:02:14.51025Z","end":"2026-05-19T06:02:14.799108Z","steps":["trace[2027725512] 'agreement among raft nodes before linearized reading' (duration: 288.610887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.79922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.964314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nftl6\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-19T06:02:14.79926Z","caller":"traceutil/trace.go:171","msg":"trace[723234711] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nftl6; range_end:; response_count:1; response_revision:621; }","duration":"124.045396ms","start":"2026-05-19T06:02:14.675202Z","end":"2026-05-19T06:02:14.799248Z","steps":["trace[723234711] 'agreement among raft nodes before linearized reading' (duration: 123.979395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.799439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.598919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.799475Z","caller":"traceutil/trace.go:171","msg":"trace[1122993004] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:621; }","duration":"186.675701ms","start":"2026-05-19T06:02:14.61279Z","end":"2026-05-19T06:02:14.799465Z","steps":["trace[1122993004] 'agreement among raft nodes before linearized reading' (duration: 186.619949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.799616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.227907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T06:02:14.800168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.656584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.80031Z","caller":"traceutil/trace.go:171","msg":"trace[960407280] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:621; }","duration":"263.877509ms","start":"2026-05-19T06:02:14.536403Z","end":"2026-05-19T06:02:14.80028Z","steps":["trace[960407280] 'agreement among raft nodes before linearized reading' (duration: 263.572901ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:14.799158Z","caller":"traceutil/trace.go:171","msg":"trace[1535130123] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"288.055452ms","start":"2026-05-19T06:02:14.511088Z","end":"2026-05-19T06:02:14.799143Z","steps":["trace[1535130123] 'process raft request' (duration: 287.652812ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:14.80023Z","caller":"traceutil/trace.go:171","msg":"trace[1783315204] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:621; }","duration":"224.857054ms","start":"2026-05-19T06:02:14.57535Z","end":"2026-05-19T06:02:14.800207Z","steps":["trace[1783315204] 'agreement among raft nodes before linearized reading' (duration: 224.198507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.799645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.570703ms","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:5"} {"level":"info","ts":"2026-05-19T06:02:14.800604Z","caller":"traceutil/trace.go:171","msg":"trace[1658048648] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:621; }","duration":"188.566899ms","start":"2026-05-19T06:02:14.612016Z","end":"2026-05-19T06:02:14.800583Z","steps":["trace[1658048648] 'agreement among raft nodes before linearized reading' (duration: 187.606394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.79973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.981763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.800799Z","caller":"traceutil/trace.go:171","msg":"trace[1347482717] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:621; }","duration":"189.067513ms","start":"2026-05-19T06:02:14.611712Z","end":"2026-05-19T06:02:14.800779Z","steps":["trace[1347482717] 'agreement among raft nodes before linearized reading' (duration: 187.997525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.799814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.504144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.800965Z","caller":"traceutil/trace.go:171","msg":"trace[1606824787] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:621; }","duration":"242.691404ms","start":"2026-05-19T06:02:14.558255Z","end":"2026-05-19T06:02:14.800946Z","steps":["trace[1606824787] 'agreement among raft nodes before linearized reading' (duration: 241.496453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.799841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.817577ms","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:5"} {"level":"info","ts":"2026-05-19T06:02:14.801044Z","caller":"traceutil/trace.go:171","msg":"trace[394721254] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:621; }","duration":"204.045449ms","start":"2026-05-19T06:02:14.596984Z","end":"2026-05-19T06:02:14.801029Z","steps":["trace[394721254] 'agreement among raft nodes before linearized reading' (duration: 202.761465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.798969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.363386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.801151Z","caller":"traceutil/trace.go:171","msg":"trace[1563881205] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:621; }","duration":"225.601223ms","start":"2026-05-19T06:02:14.575521Z","end":"2026-05-19T06:02:14.801122Z","steps":["trace[1563881205] 'agreement among raft nodes before linearized reading' (duration: 223.354865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.799871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.902679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.801321Z","caller":"traceutil/trace.go:171","msg":"trace[968293772] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:621; }","duration":"204.393177ms","start":"2026-05-19T06:02:14.5969Z","end":"2026-05-19T06:02:14.801293Z","steps":["trace[968293772] 'agreement among raft nodes before linearized reading' (duration: 202.905939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.799906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.334858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.801404Z","caller":"traceutil/trace.go:171","msg":"trace[1319375554] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:621; }","duration":"242.840676ms","start":"2026-05-19T06:02:14.558546Z","end":"2026-05-19T06:02:14.801386Z","steps":["trace[1319375554] 'agreement among raft nodes before linearized reading' (duration: 241.314757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.799885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.829259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.801484Z","caller":"traceutil/trace.go:171","msg":"trace[1322661217] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:621; }","duration":"265.494232ms","start":"2026-05-19T06:02:14.535981Z","end":"2026-05-19T06:02:14.801475Z","steps":["trace[1322661217] 'agreement among raft nodes before linearized reading' (duration: 263.623474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.799925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.032687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.801551Z","caller":"traceutil/trace.go:171","msg":"trace[1282501787] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:621; }","duration":"224.670709ms","start":"2026-05-19T06:02:14.576869Z","end":"2026-05-19T06:02:14.80154Z","steps":["trace[1282501787] 'agreement among raft nodes before linearized reading' (duration: 223.034946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.799957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.627966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.80163Z","caller":"traceutil/trace.go:171","msg":"trace[970318086] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:621; }","duration":"243.336331ms","start":"2026-05-19T06:02:14.558279Z","end":"2026-05-19T06:02:14.801616Z","steps":["trace[970318086] 'agreement among raft nodes before linearized reading' (duration: 241.650967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.799981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.922941ms","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-19T06:02:14.801738Z","caller":"traceutil/trace.go:171","msg":"trace[327636655] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:621; }","duration":"265.681586ms","start":"2026-05-19T06:02:14.536033Z","end":"2026-05-19T06:02:14.801715Z","steps":["trace[327636655] 'agreement among raft nodes before linearized reading' (duration: 263.921021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.800027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.694214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.801854Z","caller":"traceutil/trace.go:171","msg":"trace[229263530] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:621; }","duration":"291.547462ms","start":"2026-05-19T06:02:14.510297Z","end":"2026-05-19T06:02:14.801844Z","steps":["trace[229263530] 'agreement among raft nodes before linearized reading' (duration: 289.700535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:14.800061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.653733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:14.801954Z","caller":"traceutil/trace.go:171","msg":"trace[221020595] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:621; }","duration":"291.634614ms","start":"2026-05-19T06:02:14.510311Z","end":"2026-05-19T06:02:14.801946Z","steps":["trace[221020595] 'agreement among raft nodes before linearized reading' (duration: 289.571841ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:15.134086Z","caller":"traceutil/trace.go:171","msg":"trace[1816642888] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"345.938925ms","start":"2026-05-19T06:02:14.788105Z","end":"2026-05-19T06:02:15.134044Z","steps":["trace[1816642888] 'process raft request' (duration: 283.061955ms)","trace[1816642888] 'compare' (duration: 62.658604ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:02:15.134284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:14.788075Z","time spent":"346.088518ms","remote":"127.0.0.1:51200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T06:02:15.174448Z","caller":"traceutil/trace.go:171","msg":"trace[325962745] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"360.282703ms","start":"2026-05-19T06:02:14.814132Z","end":"2026-05-19T06:02:15.174415Z","steps":["trace[325962745] 'process raft request' (duration: 359.993476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:15.174629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:14.814114Z","time spent":"360.422227ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T06:02:15.857677Z","caller":"traceutil/trace.go:171","msg":"trace[5115814] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"277.854271ms","start":"2026-05-19T06:02:15.579795Z","end":"2026-05-19T06:02:15.857649Z","steps":["trace[5115814] 'process raft request' (duration: 277.360248ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:16.816721Z","caller":"traceutil/trace.go:171","msg":"trace[1201813297] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"120.264619ms","start":"2026-05-19T06:02:16.696432Z","end":"2026-05-19T06:02:16.816696Z","steps":["trace[1201813297] 'process raft request' (duration: 120.086505ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:20.988661Z","caller":"traceutil/trace.go:171","msg":"trace[1848625240] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"120.477315ms","start":"2026-05-19T06:02:20.868096Z","end":"2026-05-19T06:02:20.988574Z","steps":["trace[1848625240] 'process raft request' (duration: 120.335121ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:21.849512Z","caller":"traceutil/trace.go:171","msg":"trace[43929465] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"106.001513ms","start":"2026-05-19T06:02:21.743484Z","end":"2026-05-19T06:02:21.849486Z","steps":["trace[43929465] 'process raft request' (duration: 105.139201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:22.343468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.138842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T06:02:22.343643Z","caller":"traceutil/trace.go:171","msg":"trace[1212352331] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"413.122416ms","start":"2026-05-19T06:02:21.930485Z","end":"2026-05-19T06:02:22.343607Z","steps":["trace[1212352331] 'process raft request' (duration: 29.764338ms)","trace[1212352331] 'compare' (duration: 383.011658ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:02:22.343759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:21.930454Z","time spent":"413.26112ms","remote":"127.0.0.1:50790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T06:02:22.343608Z","caller":"traceutil/trace.go:171","msg":"trace[858374867] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"382.049644ms","start":"2026-05-19T06:02:21.961537Z","end":"2026-05-19T06:02:22.343587Z","steps":["trace[858374867] 'read index received' (duration: 104.852µs)","trace[858374867] 'applied index is now lower than readState.Index' (duration: 381.943112ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:02:22.344039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.031351ms","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-19T06:02:22.344063Z","caller":"traceutil/trace.go:171","msg":"trace[1798452332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"307.062932ms","start":"2026-05-19T06:02:22.036995Z","end":"2026-05-19T06:02:22.344058Z","steps":["trace[1798452332] 'agreement among raft nodes before linearized reading' (duration: 306.98145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:22.344065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.137655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-19T06:02:22.344089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.543696ms","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-19T06:02:22.344171Z","caller":"traceutil/trace.go:171","msg":"trace[2103133706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:714; }","duration":"382.621518ms","start":"2026-05-19T06:02:21.961525Z","end":"2026-05-19T06:02:22.344147Z","steps":["trace[2103133706] 'agreement among raft nodes before linearized reading' (duration: 382.168747ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:22.344122Z","caller":"traceutil/trace.go:171","msg":"trace[493291666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"307.239937ms","start":"2026-05-19T06:02:22.036873Z","end":"2026-05-19T06:02:22.344113Z","steps":["trace[493291666] 'agreement among raft nodes before linearized reading' (duration: 307.133965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:22.344225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:21.961469Z","time spent":"382.743262ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T06:02:22.344236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:22.03685Z","time spent":"307.36961ms","remote":"127.0.0.1:50956","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-19T06:02:22.344074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.621661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-05-19T06:02:22.344316Z","caller":"traceutil/trace.go:171","msg":"trace[18679434] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:714; }","duration":"124.894529ms","start":"2026-05-19T06:02:22.219404Z","end":"2026-05-19T06:02:22.344298Z","steps":["trace[18679434] 'agreement among raft nodes before linearized reading' (duration: 124.55674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:22.344079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:22.036933Z","time spent":"307.142163ms","remote":"127.0.0.1:50956","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-19T06:02:22.344281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.344437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-05-19T06:02:22.34438Z","caller":"traceutil/trace.go:171","msg":"trace[1319079347] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:714; }","duration":"317.502581ms","start":"2026-05-19T06:02:22.026858Z","end":"2026-05-19T06:02:22.344361Z","steps":["trace[1319079347] 'agreement among raft nodes before linearized reading' (duration: 317.09141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:22.344608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:22.026828Z","time spent":"317.761528ms","remote":"127.0.0.1:50774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-19T06:02:22.529151Z","caller":"traceutil/trace.go:171","msg":"trace[434982329] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"180.699707ms","start":"2026-05-19T06:02:22.348428Z","end":"2026-05-19T06:02:22.529128Z","steps":["trace[434982329] 'process raft request' (duration: 180.019079ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:22.52964Z","caller":"traceutil/trace.go:171","msg":"trace[443688321] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"181.035835ms","start":"2026-05-19T06:02:22.348586Z","end":"2026-05-19T06:02:22.529622Z","steps":["trace[443688321] 'process raft request' (duration: 180.47302ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:22.529585Z","caller":"traceutil/trace.go:171","msg":"trace[2071915650] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"112.403316ms","start":"2026-05-19T06:02:22.417154Z","end":"2026-05-19T06:02:22.529557Z","steps":["trace[2071915650] 'process raft request' (duration: 112.289243ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:22.52971Z","caller":"traceutil/trace.go:171","msg":"trace[850100495] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:738; }","duration":"141.27993ms","start":"2026-05-19T06:02:22.38839Z","end":"2026-05-19T06:02:22.52967Z","steps":["trace[850100495] 'read index received' (duration: 14.278318ms)","trace[850100495] 'applied index is now lower than readState.Index' (duration: 126.995072ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:02:22.529816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.410824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:02:22.529869Z","caller":"traceutil/trace.go:171","msg":"trace[22455551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"141.492387ms","start":"2026-05-19T06:02:22.388363Z","end":"2026-05-19T06:02:22.529855Z","steps":["trace[22455551] 'agreement among raft nodes before linearized reading' (duration: 141.379004ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:24.519264Z","caller":"traceutil/trace.go:171","msg":"trace[1490830153] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"107.730026ms","start":"2026-05-19T06:02:24.411506Z","end":"2026-05-19T06:02:24.519236Z","steps":["trace[1490830153] 'process raft request' (duration: 107.547001ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:33.044844Z","caller":"traceutil/trace.go:171","msg":"trace[647838539] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"445.328165ms","start":"2026-05-19T06:02:32.599488Z","end":"2026-05-19T06:02:33.044817Z","steps":["trace[647838539] 'read index received' (duration: 440.412498ms)","trace[647838539] 'applied index is now lower than readState.Index' (duration: 4.914287ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:02:33.044954Z","caller":"traceutil/trace.go:171","msg":"trace[800759536] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"487.085392ms","start":"2026-05-19T06:02:32.557859Z","end":"2026-05-19T06:02:33.044945Z","steps":["trace[800759536] 'process raft request' (duration: 486.720913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:33.045054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:32.557835Z","time spent":"487.145093ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:02:33.045155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.636093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T06:02:33.045236Z","caller":"traceutil/trace.go:171","msg":"trace[516789987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"445.734517ms","start":"2026-05-19T06:02:32.599483Z","end":"2026-05-19T06:02:33.045217Z","steps":["trace[516789987] 'agreement among raft nodes before linearized reading' (duration: 445.4959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:33.045281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:32.599464Z","time spent":"445.806998ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T06:02:33.047599Z","caller":"traceutil/trace.go:171","msg":"trace[428836635] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"235.846368ms","start":"2026-05-19T06:02:32.811731Z","end":"2026-05-19T06:02:33.047577Z","steps":["trace[428836635] 'process raft request' (duration: 235.691534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:33.047702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.294474ms","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-19T06:02:33.04776Z","caller":"traceutil/trace.go:171","msg":"trace[1480988078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"431.349295ms","start":"2026-05-19T06:02:32.616393Z","end":"2026-05-19T06:02:33.047742Z","steps":["trace[1480988078] 'agreement among raft nodes before linearized reading' (duration: 431.13811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:33.047793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:02:32.616356Z","time spent":"431.429017ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T06:02:54.17869Z","caller":"traceutil/trace.go:171","msg":"trace[721285631] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1174; }","duration":"202.288775ms","start":"2026-05-19T06:02:53.976354Z","end":"2026-05-19T06:02:54.178643Z","steps":["trace[721285631] 'read index received' (duration: 193.117654ms)","trace[721285631] 'applied index is now lower than readState.Index' (duration: 9.169291ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:02:54.179018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.620974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-19T06:02:54.17909Z","caller":"traceutil/trace.go:171","msg":"trace[235382718] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1140; }","duration":"202.779219ms","start":"2026-05-19T06:02:53.976288Z","end":"2026-05-19T06:02:54.179067Z","steps":["trace[235382718] 'agreement among raft nodes before linearized reading' (duration: 202.538402ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:54.178888Z","caller":"traceutil/trace.go:171","msg":"trace[226153794] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"221.785005ms","start":"2026-05-19T06:02:53.957038Z","end":"2026-05-19T06:02:54.178823Z","steps":["trace[226153794] 'process raft request' (duration: 212.402589ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:54.179786Z","caller":"traceutil/trace.go:171","msg":"trace[1787439695] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"197.239112ms","start":"2026-05-19T06:02:53.982504Z","end":"2026-05-19T06:02:54.179743Z","steps":["trace[1787439695] 'process raft request' (duration: 196.921223ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:02:54.179868Z","caller":"traceutil/trace.go:171","msg":"trace[639969527] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"196.897353ms","start":"2026-05-19T06:02:53.98294Z","end":"2026-05-19T06:02:54.179837Z","steps":["trace[639969527] 'process raft request' (duration: 196.641035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:02:54.180169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.575595ms","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-19T06:02:54.180291Z","caller":"traceutil/trace.go:171","msg":"trace[31361931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1142; }","duration":"161.917013ms","start":"2026-05-19T06:02:54.018354Z","end":"2026-05-19T06:02:54.180271Z","steps":["trace[31361931] 'agreement among raft nodes before linearized reading' (duration: 161.414471ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:03:02.073953Z","caller":"traceutil/trace.go:171","msg":"trace[97117311] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"506.861637ms","start":"2026-05-19T06:03:01.567073Z","end":"2026-05-19T06:03:02.073934Z","steps":["trace[97117311] 'process raft request' (duration: 506.732542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:02.07408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:03:01.567045Z","time spent":"506.97405ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T06:03:02.477902Z","caller":"traceutil/trace.go:171","msg":"trace[1841160383] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"252.107723ms","start":"2026-05-19T06:03:02.225762Z","end":"2026-05-19T06:03:02.47787Z","steps":["trace[1841160383] 'read index received' (duration: 193.063972ms)","trace[1841160383] 'applied index is now lower than readState.Index' (duration: 59.042331ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:03:02.478Z","caller":"traceutil/trace.go:171","msg":"trace[1562298194] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"272.189611ms","start":"2026-05-19T06:03:02.205765Z","end":"2026-05-19T06:03:02.477955Z","steps":["trace[1562298194] 'process raft request' (duration: 212.986875ms)","trace[1562298194] 'compare' (duration: 58.974399ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:03:02.47817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.397602ms","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-19T06:03:02.478203Z","caller":"traceutil/trace.go:171","msg":"trace[1349891947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"252.510554ms","start":"2026-05-19T06:03:02.225685Z","end":"2026-05-19T06:03:02.478196Z","steps":["trace[1349891947] 'agreement among raft nodes before linearized reading' (duration: 252.3439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:02.478183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.686004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T06:03:02.478241Z","caller":"traceutil/trace.go:171","msg":"trace[809212193] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1191; }","duration":"221.753826ms","start":"2026-05-19T06:03:02.256472Z","end":"2026-05-19T06:03:02.478226Z","steps":["trace[809212193] 'agreement among raft nodes before linearized reading' (duration: 221.631833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:02.478449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.714998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T06:03:02.478569Z","caller":"traceutil/trace.go:171","msg":"trace[955805203] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1191; }","duration":"196.911263ms","start":"2026-05-19T06:03:02.281631Z","end":"2026-05-19T06:03:02.478542Z","steps":["trace[955805203] 'agreement among raft nodes before linearized reading' (duration: 196.676407ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:03:03.476879Z","caller":"traceutil/trace.go:171","msg":"trace[463616046] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"100.117781ms","start":"2026-05-19T06:03:03.376673Z","end":"2026-05-19T06:03:03.476791Z","steps":["trace[463616046] 'process raft request' (duration: 88.011143ms)","trace[463616046] 'compare' (duration: 11.755819ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:03:25.933489Z","caller":"traceutil/trace.go:171","msg":"trace[992912849] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1456; }","duration":"108.414018ms","start":"2026-05-19T06:03:25.825036Z","end":"2026-05-19T06:03:25.93345Z","steps":["trace[992912849] 'process raft request' (duration: 108.10361ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:03:42.722586Z","caller":"traceutil/trace.go:171","msg":"trace[1709866222] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"156.358037ms","start":"2026-05-19T06:03:42.566188Z","end":"2026-05-19T06:03:42.722546Z","steps":["trace[1709866222] 'process raft request' (duration: 156.106401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:51.142486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.718296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:03:51.142576Z","caller":"traceutil/trace.go:171","msg":"trace[1982357820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1871; }","duration":"760.85718ms","start":"2026-05-19T06:03:50.381697Z","end":"2026-05-19T06:03:51.142554Z","steps":["trace[1982357820] 'range keys from in-memory index tree' (duration: 760.627484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:51.142617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:03:50.381671Z","time spent":"760.936762ms","remote":"127.0.0.1:50716","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-19T06:03:51.142766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.90733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-00aae77e-c36f-4c36-8250-60bc1159eae7\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-19T06:03:51.142903Z","caller":"traceutil/trace.go:171","msg":"trace[1098451173] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-00aae77e-c36f-4c36-8250-60bc1159eae7; range_end:; response_count:1; response_revision:1871; }","duration":"383.032205ms","start":"2026-05-19T06:03:50.759838Z","end":"2026-05-19T06:03:51.14287Z","steps":["trace[1098451173] 'range keys from in-memory index tree' (duration: 382.682594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:51.14297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:03:50.759771Z","time spent":"383.183208ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-00aae77e-c36f-4c36-8250-60bc1159eae7\" "} {"level":"warn","ts":"2026-05-19T06:03:51.142795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.385552ms","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-19T06:03:51.143045Z","caller":"traceutil/trace.go:171","msg":"trace[754262160] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1871; }","duration":"670.630409ms","start":"2026-05-19T06:03:50.472397Z","end":"2026-05-19T06:03:51.143028Z","steps":["trace[754262160] 'count revisions from in-memory index tree' (duration: 670.269019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:51.143091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:03:50.472306Z","time spent":"670.768643ms","remote":"127.0.0.1:43778","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":"warn","ts":"2026-05-19T06:03:51.143271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.288015ms","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-19T06:03:51.143377Z","caller":"traceutil/trace.go:171","msg":"trace[1759446827] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1871; }","duration":"795.453509ms","start":"2026-05-19T06:03:50.347891Z","end":"2026-05-19T06:03:51.143344Z","steps":["trace[1759446827] 'range keys from in-memory index tree' (duration: 794.668559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:51.144111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:03:50.347848Z","time spent":"796.24225ms","remote":"127.0.0.1:44504","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-19T06:03:51.308031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859045319,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T06:03:51.402163Z","caller":"traceutil/trace.go:171","msg":"trace[1872259401] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"639.78817ms","start":"2026-05-19T06:03:50.762339Z","end":"2026-05-19T06:03:51.402127Z","steps":["trace[1872259401] 'process raft request' (duration: 639.639946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:51.402337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:03:50.762311Z","time spent":"639.935793ms","remote":"127.0.0.1:50956","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-19T06:03:51.423613Z","caller":"traceutil/trace.go:171","msg":"trace[263729502] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1942; }","duration":"615.763028ms","start":"2026-05-19T06:03:50.80783Z","end":"2026-05-19T06:03:51.423593Z","steps":["trace[263729502] 'read index received' (duration: 594.109399ms)","trace[263729502] 'applied index is now lower than readState.Index' (duration: 21.650819ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:03:51.423752Z","caller":"traceutil/trace.go:171","msg":"trace[1872961220] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"364.748773ms","start":"2026-05-19T06:03:51.058991Z","end":"2026-05-19T06:03:51.423739Z","steps":["trace[1872961220] 'process raft request' (duration: 364.490145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:51.423844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:03:51.058953Z","time spent":"364.823345ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:03:51.423969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.126118ms","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-19T06:03:51.424053Z","caller":"traceutil/trace.go:171","msg":"trace[695145892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1873; }","duration":"616.22131ms","start":"2026-05-19T06:03:50.807814Z","end":"2026-05-19T06:03:51.424035Z","steps":["trace[695145892] 'agreement among raft nodes before linearized reading' (duration: 615.958184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:51.424106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:03:50.807764Z","time spent":"616.329973ms","remote":"127.0.0.1:50956","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-19T06:03:51.435254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.417418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-00aae77e-c36f-4c36-8250-60bc1159eae7\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-19T06:03:51.435314Z","caller":"traceutil/trace.go:171","msg":"trace[167478599] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-00aae77e-c36f-4c36-8250-60bc1159eae7; range_end:; response_count:1; response_revision:1874; }","duration":"242.512311ms","start":"2026-05-19T06:03:51.192789Z","end":"2026-05-19T06:03:51.435301Z","steps":["trace[167478599] 'agreement among raft nodes before linearized reading' (duration: 242.338357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:51.435404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.091737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:03:51.435473Z","caller":"traceutil/trace.go:171","msg":"trace[958466425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1874; }","duration":"291.19819ms","start":"2026-05-19T06:03:51.14425Z","end":"2026-05-19T06:03:51.435449Z","steps":["trace[958466425] 'agreement among raft nodes before linearized reading' (duration: 291.070996ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:03:51.547342Z","caller":"traceutil/trace.go:171","msg":"trace[171314796] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"119.473839ms","start":"2026-05-19T06:03:51.427841Z","end":"2026-05-19T06:03:51.547315Z","steps":["trace[171314796] 'process raft request' (duration: 51.164804ms)","trace[171314796] 'compare' (duration: 68.189532ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:03:51.553805Z","caller":"traceutil/trace.go:171","msg":"trace[1489926121] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1945; }","duration":"111.917509ms","start":"2026-05-19T06:03:51.441867Z","end":"2026-05-19T06:03:51.553785Z","steps":["trace[1489926121] 'read index received' (duration: 37.184057ms)","trace[1489926121] 'applied index is now lower than readState.Index' (duration: 74.732712ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:03:51.553899Z","caller":"traceutil/trace.go:171","msg":"trace[1792149869] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"112.797855ms","start":"2026-05-19T06:03:51.44108Z","end":"2026-05-19T06:03:51.553878Z","steps":["trace[1792149869] 'process raft request' (duration: 112.594009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:51.554063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.178807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:03:51.554099Z","caller":"traceutil/trace.go:171","msg":"trace[170621439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1876; }","duration":"112.245369ms","start":"2026-05-19T06:03:51.441843Z","end":"2026-05-19T06:03:51.554089Z","steps":["trace[170621439] 'agreement among raft nodes before linearized reading' (duration: 112.042444ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:03:52.213152Z","caller":"traceutil/trace.go:171","msg":"trace[530143099] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1948; }","duration":"441.54352ms","start":"2026-05-19T06:03:51.77158Z","end":"2026-05-19T06:03:52.213123Z","steps":["trace[530143099] 'read index received' (duration: 441.340555ms)","trace[530143099] 'applied index is now lower than readState.Index' (duration: 201.385µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:03:52.213751Z","caller":"traceutil/trace.go:171","msg":"trace[1999261675] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"446.385858ms","start":"2026-05-19T06:03:51.766781Z","end":"2026-05-19T06:03:52.213167Z","steps":["trace[1999261675] 'process raft request' (duration: 446.150092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:52.213955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:03:51.766747Z","time spent":"447.082316ms","remote":"127.0.0.1:50956","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-19T06:03:52.214522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.750192ms","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-19T06:03:52.214851Z","caller":"traceutil/trace.go:171","msg":"trace[424449383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1878; }","duration":"443.114202ms","start":"2026-05-19T06:03:51.771516Z","end":"2026-05-19T06:03:52.21463Z","steps":["trace[424449383] 'agreement among raft nodes before linearized reading' (duration: 441.756696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:52.214992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:03:51.771484Z","time spent":"443.445481ms","remote":"127.0.0.1:50956","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-19T06:03:52.241672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.836804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T06:03:52.241769Z","caller":"traceutil/trace.go:171","msg":"trace[1694757809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1879; }","duration":"469.998628ms","start":"2026-05-19T06:03:51.771749Z","end":"2026-05-19T06:03:52.241747Z","steps":["trace[1694757809] 'agreement among raft nodes before linearized reading' (duration: 469.768032ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:03:52.241756Z","caller":"traceutil/trace.go:171","msg":"trace[901466526] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"160.581979ms","start":"2026-05-19T06:03:52.081129Z","end":"2026-05-19T06:03:52.241711Z","steps":["trace[901466526] 'process raft request' (duration: 160.184079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:52.241813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:03:51.771731Z","time spent":"470.07297ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T06:03:52.241857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.286369ms","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-19T06:03:52.241985Z","caller":"traceutil/trace.go:171","msg":"trace[1467654056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1879; }","duration":"469.476674ms","start":"2026-05-19T06:03:51.772484Z","end":"2026-05-19T06:03:52.241961Z","steps":["trace[1467654056] 'agreement among raft nodes before linearized reading' (duration: 469.229018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:03:52.242049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:03:51.772462Z","time spent":"469.571458ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T06:03:57.566492Z","caller":"traceutil/trace.go:171","msg":"trace[2099251756] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"147.986594ms","start":"2026-05-19T06:03:57.41848Z","end":"2026-05-19T06:03:57.566467Z","steps":["trace[2099251756] 'process raft request' (duration: 147.83743ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:03:57.580914Z","caller":"traceutil/trace.go:171","msg":"trace[179827160] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"111.450844ms","start":"2026-05-19T06:03:57.469435Z","end":"2026-05-19T06:03:57.580886Z","steps":["trace[179827160] 'process raft request' (duration: 111.27761ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:03.783424Z","caller":"traceutil/trace.go:171","msg":"trace[993592454] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"161.656421ms","start":"2026-05-19T06:04:03.62174Z","end":"2026-05-19T06:04:03.783396Z","steps":["trace[993592454] 'process raft request' (duration: 150.741704ms)","trace[993592454] 'compare' (duration: 10.301581ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:04:03.783563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.520073ms","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-19T06:04:03.783593Z","caller":"traceutil/trace.go:171","msg":"trace[61580432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1977; }","duration":"109.603975ms","start":"2026-05-19T06:04:03.673981Z","end":"2026-05-19T06:04:03.783585Z","steps":["trace[61580432] 'agreement among raft nodes before linearized reading' (duration: 109.481562ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:03.783424Z","caller":"traceutil/trace.go:171","msg":"trace[1053077778] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2050; }","duration":"109.217546ms","start":"2026-05-19T06:04:03.674037Z","end":"2026-05-19T06:04:03.783254Z","steps":["trace[1053077778] 'read index received' (duration: 98.303409ms)","trace[1053077778] 'applied index is now lower than readState.Index' (duration: 10.910007ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:04:08.421006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.364728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T06:04:08.421265Z","caller":"traceutil/trace.go:171","msg":"trace[44939536] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"129.120379ms","start":"2026-05-19T06:04:08.292119Z","end":"2026-05-19T06:04:08.42124Z","steps":["trace[44939536] 'process raft request' (duration: 129.013015ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:08.421311Z","caller":"traceutil/trace.go:171","msg":"trace[1340750425] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"440.389704ms","start":"2026-05-19T06:04:07.980875Z","end":"2026-05-19T06:04:08.421264Z","steps":["trace[1340750425] 'process raft request' (duration: 149.676616ms)","trace[1340750425] 'compare' (duration: 290.222495ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:04:08.421509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:07.980852Z","time spent":"440.546378ms","remote":"127.0.0.1:50748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T06:04:09.551408Z","caller":"traceutil/trace.go:171","msg":"trace[172732466] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2091; }","duration":"170.859393ms","start":"2026-05-19T06:04:09.380525Z","end":"2026-05-19T06:04:09.551384Z","steps":["trace[172732466] 'read index received' (duration: 170.631137ms)","trace[172732466] 'applied index is now lower than readState.Index' (duration: 221.896µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:04:09.551491Z","caller":"traceutil/trace.go:171","msg":"trace[1113220398] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"313.310491ms","start":"2026-05-19T06:04:09.238159Z","end":"2026-05-19T06:04:09.55147Z","steps":["trace[1113220398] 'process raft request' (duration: 312.981313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:04:09.551572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.046397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T06:04:09.551584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:09.238129Z","time spent":"313.396164ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T06:04:09.551606Z","caller":"traceutil/trace.go:171","msg":"trace[1651145915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2017; }","duration":"171.088928ms","start":"2026-05-19T06:04:09.380508Z","end":"2026-05-19T06:04:09.551597Z","steps":["trace[1651145915] 'agreement among raft nodes before linearized reading' (duration: 170.997536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:04:09.551735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.722397ms","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-19T06:04:09.551827Z","caller":"traceutil/trace.go:171","msg":"trace[1903950951] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2017; }","duration":"120.876981ms","start":"2026-05-19T06:04:09.430929Z","end":"2026-05-19T06:04:09.551806Z","steps":["trace[1903950951] 'agreement among raft nodes before linearized reading' (duration: 120.597574ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:40.845587Z","caller":"traceutil/trace.go:171","msg":"trace[456222823] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2270; }","duration":"285.788279ms","start":"2026-05-19T06:04:40.559772Z","end":"2026-05-19T06:04:40.84556Z","steps":["trace[456222823] 'read index received' (duration: 285.616634ms)","trace[456222823] 'applied index is now lower than readState.Index' (duration: 170.325µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:04:40.845679Z","caller":"traceutil/trace.go:171","msg":"trace[960647451] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"311.323988ms","start":"2026-05-19T06:04:40.534299Z","end":"2026-05-19T06:04:40.845623Z","steps":["trace[960647451] 'process raft request' (duration: 311.04672ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:40.845714Z","caller":"traceutil/trace.go:171","msg":"trace[129777666] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"311.348799ms","start":"2026-05-19T06:04:40.534304Z","end":"2026-05-19T06:04:40.845653Z","steps":["trace[129777666] 'process raft request' (duration: 311.203605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:04:40.84598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:40.534284Z","time spent":"311.564335ms","remote":"127.0.0.1:50956","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-19T06:04:40.845997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:40.534264Z","time spent":"311.558064ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:04:40.846028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.235539ms","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-19T06:04:40.846143Z","caller":"traceutil/trace.go:171","msg":"trace[1944314655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2190; }","duration":"286.406665ms","start":"2026-05-19T06:04:40.559707Z","end":"2026-05-19T06:04:40.846113Z","steps":["trace[1944314655] 'agreement among raft nodes before linearized reading' (duration: 286.017355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:04:40.846283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.766355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T06:04:40.846359Z","caller":"traceutil/trace.go:171","msg":"trace[1296646688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2190; }","duration":"259.889339ms","start":"2026-05-19T06:04:40.586453Z","end":"2026-05-19T06:04:40.846342Z","steps":["trace[1296646688] 'agreement among raft nodes before linearized reading' (duration: 259.712935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:04:40.846573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.883897ms","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-19T06:04:40.846594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.657074ms","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-19T06:04:40.84666Z","caller":"traceutil/trace.go:171","msg":"trace[306238062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2190; }","duration":"272.815768ms","start":"2026-05-19T06:04:40.573829Z","end":"2026-05-19T06:04:40.846645Z","steps":["trace[306238062] 'agreement among raft nodes before linearized reading' (duration: 271.897194ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:40.846839Z","caller":"traceutil/trace.go:171","msg":"trace[1253391884] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2190; }","duration":"194.02448ms","start":"2026-05-19T06:04:40.652614Z","end":"2026-05-19T06:04:40.846638Z","steps":["trace[1253391884] 'agreement among raft nodes before linearized reading' (duration: 193.434535ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:47.017573Z","caller":"traceutil/trace.go:171","msg":"trace[1455543470] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"151.994247ms","start":"2026-05-19T06:04:46.865553Z","end":"2026-05-19T06:04:47.017548Z","steps":["trace[1455543470] 'process raft request' (duration: 151.802532ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:47.030636Z","caller":"traceutil/trace.go:171","msg":"trace[265941082] linearizableReadLoop","detail":"{readStateIndex:2305; appliedIndex:2303; }","duration":"144.193194ms","start":"2026-05-19T06:04:46.886415Z","end":"2026-05-19T06:04:47.030608Z","steps":["trace[265941082] 'read index received' (duration: 131.004808ms)","trace[265941082] 'applied index is now lower than readState.Index' (duration: 13.187486ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:04:47.03078Z","caller":"traceutil/trace.go:171","msg":"trace[686472494] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"165.116472ms","start":"2026-05-19T06:04:46.865633Z","end":"2026-05-19T06:04:47.030749Z","steps":["trace[686472494] 'process raft request' (duration: 164.843274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:04:47.030868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.42829ms","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-19T06:04:47.030919Z","caller":"traceutil/trace.go:171","msg":"trace[825824101] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2222; }","duration":"144.512802ms","start":"2026-05-19T06:04:46.886387Z","end":"2026-05-19T06:04:47.0309Z","steps":["trace[825824101] 'agreement among raft nodes before linearized reading' (duration: 144.353528ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:47.160231Z","caller":"traceutil/trace.go:171","msg":"trace[1902656581] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"125.608464ms","start":"2026-05-19T06:04:47.034595Z","end":"2026-05-19T06:04:47.160204Z","steps":["trace[1902656581] 'process raft request' (duration: 94.742005ms)","trace[1902656581] 'compare' (duration: 30.672445ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:04:47.160496Z","caller":"traceutil/trace.go:171","msg":"trace[649890568] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"124.933518ms","start":"2026-05-19T06:04:47.035552Z","end":"2026-05-19T06:04:47.160486Z","steps":["trace[649890568] 'process raft request' (duration: 124.612039ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:47.160803Z","caller":"traceutil/trace.go:171","msg":"trace[904377824] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"124.938147ms","start":"2026-05-19T06:04:47.035854Z","end":"2026-05-19T06:04:47.160792Z","steps":["trace[904377824] 'process raft request' (duration: 124.594569ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:48.148547Z","caller":"traceutil/trace.go:171","msg":"trace[451067346] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"391.427199ms","start":"2026-05-19T06:04:47.757097Z","end":"2026-05-19T06:04:48.148525Z","steps":["trace[451067346] 'process raft request' (duration: 391.269825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:04:48.148702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:47.757073Z","time spent":"391.546413ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:04:48.605202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:48.264853Z","time spent":"340.341219ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-19T06:04:48.605382Z","caller":"traceutil/trace.go:171","msg":"trace[1378649440] linearizableReadLoop","detail":"{readStateIndex:2310; appliedIndex:2309; }","duration":"627.627647ms","start":"2026-05-19T06:04:47.977724Z","end":"2026-05-19T06:04:48.605351Z","steps":["trace[1378649440] 'read index received' (duration: 170.785981ms)","trace[1378649440] 'applied index is now lower than readState.Index' (duration: 456.840556ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:04:48.605502Z","caller":"traceutil/trace.go:171","msg":"trace[2092216062] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"835.335856ms","start":"2026-05-19T06:04:47.770153Z","end":"2026-05-19T06:04:48.605489Z","steps":["trace[2092216062] 'process raft request' (duration: 782.576053ms)","trace[2092216062] 'compare' (duration: 52.395943ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:04:48.605615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:47.770139Z","time spent":"835.397678ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:04:48.605835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.100729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-19T06:04:48.605897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.578571ms","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-19T06:04:48.606088Z","caller":"traceutil/trace.go:171","msg":"trace[810589601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2227; }","duration":"588.797898ms","start":"2026-05-19T06:04:48.017271Z","end":"2026-05-19T06:04:48.606069Z","steps":["trace[810589601] 'agreement among raft nodes before linearized reading' (duration: 588.432608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:04:48.60614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:48.017252Z","time spent":"588.87645ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T06:04:48.60612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.296121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T06:04:48.606138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.519845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T06:04:48.606154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.15519ms","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-19T06:04:48.605909Z","caller":"traceutil/trace.go:171","msg":"trace[401566847] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:2227; }","duration":"628.227362ms","start":"2026-05-19T06:04:47.977662Z","end":"2026-05-19T06:04:48.605889Z","steps":["trace[401566847] 'agreement among raft nodes before linearized reading' (duration: 628.025137ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:48.606207Z","caller":"traceutil/trace.go:171","msg":"trace[689932608] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2227; }","duration":"337.657209ms","start":"2026-05-19T06:04:48.268537Z","end":"2026-05-19T06:04:48.606195Z","steps":["trace[689932608] 'agreement among raft nodes before linearized reading' (duration: 337.543356ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:48.606214Z","caller":"traceutil/trace.go:171","msg":"trace[1489497197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2227; }","duration":"225.399824ms","start":"2026-05-19T06:04:48.38079Z","end":"2026-05-19T06:04:48.60619Z","steps":["trace[1489497197] 'agreement among raft nodes before linearized reading' (duration: 225.24124ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:04:48.606221Z","caller":"traceutil/trace.go:171","msg":"trace[519957343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2227; }","duration":"527.261533ms","start":"2026-05-19T06:04:48.078941Z","end":"2026-05-19T06:04:48.606202Z","steps":["trace[519957343] 'agreement among raft nodes before linearized reading' (duration: 527.053158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:04:48.606209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.527386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b0e2497bbdd43b\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-05-19T06:04:48.606259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:48.26851Z","time spent":"337.73644ms","remote":"127.0.0.1:51120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-19T06:04:48.606285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:48.078918Z","time spent":"527.350856ms","remote":"127.0.0.1:50956","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-19T06:04:48.606312Z","caller":"traceutil/trace.go:171","msg":"trace[128607987] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b0e2497bbdd43b; range_end:; response_count:1; response_revision:2227; }","duration":"560.65611ms","start":"2026-05-19T06:04:48.045624Z","end":"2026-05-19T06:04:48.60628Z","steps":["trace[128607987] 'agreement among raft nodes before linearized reading' (duration: 560.391923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:04:48.606302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:47.977637Z","time spent":"628.617662ms","remote":"127.0.0.1:50748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-05-19T06:04:48.606358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:48.045616Z","time spent":"560.732492ms","remote":"127.0.0.1:50790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b0e2497bbdd43b\" "} {"level":"warn","ts":"2026-05-19T06:04:48.606227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.498409ms","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-19T06:04:48.606575Z","caller":"traceutil/trace.go:171","msg":"trace[832349044] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2227; }","duration":"567.830968ms","start":"2026-05-19T06:04:48.038693Z","end":"2026-05-19T06:04:48.606524Z","steps":["trace[832349044] 'agreement among raft nodes before linearized reading' (duration: 567.326036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:04:48.606658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:04:48.038678Z","time spent":"567.959672ms","remote":"127.0.0.1:44504","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-19T06:05:17.683576Z","caller":"traceutil/trace.go:171","msg":"trace[1478079341] transaction","detail":"{read_only:false; response_revision:2527; number_of_response:1; }","duration":"302.235901ms","start":"2026-05-19T06:05:17.381304Z","end":"2026-05-19T06:05:17.68354Z","steps":["trace[1478079341] 'process raft request' (duration: 276.666533ms)","trace[1478079341] 'compare' (duration: 24.843769ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:05:17.683791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:05:17.381287Z","time spent":"302.428626ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":361,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T06:05:18.630091Z","caller":"traceutil/trace.go:171","msg":"trace[49029255] linearizableReadLoop","detail":"{readStateIndex:2659; appliedIndex:2658; }","duration":"246.763593ms","start":"2026-05-19T06:05:18.383289Z","end":"2026-05-19T06:05:18.630052Z","steps":["trace[49029255] 'read index received' (duration: 246.453856ms)","trace[49029255] 'applied index is now lower than readState.Index' (duration: 307.847µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:05:18.630189Z","caller":"traceutil/trace.go:171","msg":"trace[1484517232] transaction","detail":"{read_only:false; response_revision:2563; number_of_response:1; }","duration":"307.737582ms","start":"2026-05-19T06:05:18.322413Z","end":"2026-05-19T06:05:18.63015Z","steps":["trace[1484517232] 'process raft request' (duration: 307.408944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:05:18.630329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.03363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T06:05:18.630382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:05:18.322384Z","time spent":"307.896838ms","remote":"127.0.0.1:57304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2483,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T06:05:18.63042Z","caller":"traceutil/trace.go:171","msg":"trace[541069145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2563; }","duration":"247.158023ms","start":"2026-05-19T06:05:18.383221Z","end":"2026-05-19T06:05:18.630379Z","steps":["trace[541069145] 'agreement among raft nodes before linearized reading' (duration: 247.01621ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:05:22.526812Z","caller":"traceutil/trace.go:171","msg":"trace[586454632] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2684; }","duration":"145.66828ms","start":"2026-05-19T06:05:22.381108Z","end":"2026-05-19T06:05:22.526776Z","steps":["trace[586454632] 'read index received' (duration: 145.360353ms)","trace[586454632] 'applied index is now lower than readState.Index' (duration: 306.367µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:05:22.526923Z","caller":"traceutil/trace.go:171","msg":"trace[660521425] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"163.133221ms","start":"2026-05-19T06:05:22.363754Z","end":"2026-05-19T06:05:22.526887Z","steps":["trace[660521425] 'process raft request' (duration: 162.792222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:05:22.527003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.864896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:05:22.527057Z","caller":"traceutil/trace.go:171","msg":"trace[638772542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2589; }","duration":"145.991889ms","start":"2026-05-19T06:05:22.381048Z","end":"2026-05-19T06:05:22.52704Z","steps":["trace[638772542] 'agreement among raft nodes before linearized reading' (duration: 145.872976ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:05:27.521241Z","caller":"traceutil/trace.go:171","msg":"trace[1437647595] transaction","detail":"{read_only:false; response_revision:2626; number_of_response:1; }","duration":"153.180983ms","start":"2026-05-19T06:05:27.368034Z","end":"2026-05-19T06:05:27.521215Z","steps":["trace[1437647595] 'process raft request' (duration: 153.004709ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:05:27.542191Z","caller":"traceutil/trace.go:171","msg":"trace[183722023] transaction","detail":"{read_only:false; response_revision:2627; number_of_response:1; }","duration":"172.80396ms","start":"2026-05-19T06:05:27.369367Z","end":"2026-05-19T06:05:27.542171Z","steps":["trace[183722023] 'process raft request' (duration: 172.664796ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:05:27.542433Z","caller":"traceutil/trace.go:171","msg":"trace[984111902] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"172.971805ms","start":"2026-05-19T06:05:27.369413Z","end":"2026-05-19T06:05:27.542385Z","steps":["trace[984111902] 'process raft request' (duration: 172.721938ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:05:31.35839Z","caller":"traceutil/trace.go:171","msg":"trace[1065869136] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"130.666824ms","start":"2026-05-19T06:05:31.227677Z","end":"2026-05-19T06:05:31.358344Z","steps":["trace[1065869136] 'process raft request' (duration: 130.49419ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:05:31.655942Z","caller":"traceutil/trace.go:171","msg":"trace[1956985664] linearizableReadLoop","detail":"{readStateIndex:2747; appliedIndex:2746; }","duration":"274.566739ms","start":"2026-05-19T06:05:31.381337Z","end":"2026-05-19T06:05:31.655904Z","steps":["trace[1956985664] 'read index received' (duration: 273.03844ms)","trace[1956985664] 'applied index is now lower than readState.Index' (duration: 1.526169ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:05:31.656368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.011461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:05:31.656401Z","caller":"traceutil/trace.go:171","msg":"trace[1470503617] transaction","detail":"{read_only:false; response_revision:2649; number_of_response:1; }","duration":"427.934478ms","start":"2026-05-19T06:05:31.228412Z","end":"2026-05-19T06:05:31.656346Z","steps":["trace[1470503617] 'process raft request' (duration: 425.966377ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:05:31.656453Z","caller":"traceutil/trace.go:171","msg":"trace[369575291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2649; }","duration":"275.141894ms","start":"2026-05-19T06:05:31.381292Z","end":"2026-05-19T06:05:31.656434Z","steps":["trace[369575291] 'agreement among raft nodes before linearized reading' (duration: 275.013581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:05:31.656428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.848895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T06:05:31.656579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:05:31.228395Z","time spent":"428.092541ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T06:05:31.656591Z","caller":"traceutil/trace.go:171","msg":"trace[2060754658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2649; }","duration":"120.181093ms","start":"2026-05-19T06:05:31.536395Z","end":"2026-05-19T06:05:31.656576Z","steps":["trace[2060754658] 'agreement among raft nodes before linearized reading' (duration: 119.941158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:05:52.131352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.230298ms","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-19T06:05:52.131449Z","caller":"traceutil/trace.go:171","msg":"trace[162053231] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2772; }","duration":"150.393223ms","start":"2026-05-19T06:05:51.981035Z","end":"2026-05-19T06:05:52.131428Z","steps":["trace[162053231] 'count revisions from in-memory index tree' (duration: 150.132845ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:05:54.7499Z","caller":"traceutil/trace.go:171","msg":"trace[471883233] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"120.507473ms","start":"2026-05-19T06:05:54.629363Z","end":"2026-05-19T06:05:54.749871Z","steps":["trace[471883233] 'process raft request' (duration: 120.308008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:05:55.290729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.497502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T06:05:55.290971Z","caller":"traceutil/trace.go:171","msg":"trace[69240584] linearizableReadLoop","detail":"{readStateIndex:2891; appliedIndex:2889; }","duration":"183.150596ms","start":"2026-05-19T06:05:55.107799Z","end":"2026-05-19T06:05:55.29095Z","steps":["trace[69240584] 'read index received' (duration: 168.966001ms)","trace[69240584] 'applied index is now lower than readState.Index' (duration: 14.183305ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:05:55.291095Z","caller":"traceutil/trace.go:171","msg":"trace[520075318] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"431.593692ms","start":"2026-05-19T06:05:54.859486Z","end":"2026-05-19T06:05:55.29108Z","steps":["trace[520075318] 'process raft request' (duration: 431.377997ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:05:55.291128Z","caller":"traceutil/trace.go:171","msg":"trace[1879432806] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"574.048621ms","start":"2026-05-19T06:05:54.717048Z","end":"2026-05-19T06:05:55.291096Z","steps":["trace[1879432806] 'process raft request' (duration: 361.055246ms)","trace[1879432806] 'compare' (duration: 212.382459ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:05:55.2912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:05:54.859459Z","time spent":"431.683325ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:05:55.291296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.483564ms","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-19T06:05:55.291423Z","caller":"traceutil/trace.go:171","msg":"trace[255893759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2787; }","duration":"183.653199ms","start":"2026-05-19T06:05:55.10775Z","end":"2026-05-19T06:05:55.291404Z","steps":["trace[255893759] 'agreement among raft nodes before linearized reading' (duration: 183.401232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:05:55.291448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.849319ms","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-19T06:05:55.291321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:05:54.717025Z","time spent":"574.179473ms","remote":"127.0.0.1:50956","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-19T06:05:55.291577Z","caller":"traceutil/trace.go:171","msg":"trace[2109506831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2787; }","duration":"138.018293ms","start":"2026-05-19T06:05:55.153528Z","end":"2026-05-19T06:05:55.291546Z","steps":["trace[2109506831] 'agreement among raft nodes before linearized reading' (duration: 137.664604ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:06:24.92079Z","caller":"traceutil/trace.go:171","msg":"trace[1580107863] transaction","detail":"{read_only:false; response_revision:2949; number_of_response:1; }","duration":"152.689194ms","start":"2026-05-19T06:06:24.768061Z","end":"2026-05-19T06:06:24.92075Z","steps":["trace[1580107863] 'process raft request' (duration: 152.558529ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:06:25.539156Z","caller":"traceutil/trace.go:171","msg":"trace[215822897] transaction","detail":"{read_only:false; response_revision:2951; number_of_response:1; }","duration":"129.452713ms","start":"2026-05-19T06:06:25.409668Z","end":"2026-05-19T06:06:25.539121Z","steps":["trace[215822897] 'process raft request' (duration: 127.507692ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:06:25.679514Z","caller":"traceutil/trace.go:171","msg":"trace[1350623930] transaction","detail":"{read_only:false; response_revision:2953; number_of_response:1; }","duration":"136.037776ms","start":"2026-05-19T06:06:25.543426Z","end":"2026-05-19T06:06:25.679464Z","steps":["trace[1350623930] 'process raft request' (duration: 106.598384ms)","trace[1350623930] 'compare' (duration: 29.289078ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:06:25.679607Z","caller":"traceutil/trace.go:171","msg":"trace[1662785311] transaction","detail":"{read_only:false; response_revision:2954; number_of_response:1; }","duration":"126.487135ms","start":"2026-05-19T06:06:25.553098Z","end":"2026-05-19T06:06:25.679585Z","steps":["trace[1662785311] 'process raft request' (duration: 126.323741ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:06:27.126982Z","caller":"traceutil/trace.go:171","msg":"trace[1843179983] transaction","detail":"{read_only:false; response_revision:2961; number_of_response:1; }","duration":"210.884447ms","start":"2026-05-19T06:06:26.91608Z","end":"2026-05-19T06:06:27.126965Z","steps":["trace[1843179983] 'process raft request' (duration: 210.765054ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:06:34.188331Z","caller":"traceutil/trace.go:171","msg":"trace[1208732596] transaction","detail":"{read_only:false; response_revision:3000; number_of_response:1; }","duration":"134.838164ms","start":"2026-05-19T06:06:34.053442Z","end":"2026-05-19T06:06:34.18828Z","steps":["trace[1208732596] 'process raft request' (duration: 134.572427ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:06:48.149348Z","caller":"traceutil/trace.go:171","msg":"trace[1560916208] transaction","detail":"{read_only:false; response_revision:3075; number_of_response:1; }","duration":"144.018355ms","start":"2026-05-19T06:06:48.005305Z","end":"2026-05-19T06:06:48.149324Z","steps":["trace[1560916208] 'process raft request' (duration: 143.874271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:14.435615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859048591,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T06:07:14.936089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859048591,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T06:07:15.436784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859048591,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T06:07:15.939079Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859048591,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T06:07:16.380099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999943906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-19T06:07:16.380306Z","caller":"traceutil/trace.go:171","msg":"trace[1022436165] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000152391s","start":"2026-05-19T06:07:14.380085Z","end":"2026-05-19T06:07:16.380238Z","steps":["trace[1022436165] 'agreement among raft nodes before linearized reading' (duration: 1.999982277s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:16.380452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:14.380063Z","time spent":"2.000375108s","remote":"127.0.0.1:50716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/19 06:07:16 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T06:07:16.439884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859048591,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T06:07:16.940062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859048591,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T06:07:17.440979Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859048591,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T06:07:17.942002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859048591,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T06:07:18.380711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000318787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-19T06:07:18.380834Z","caller":"traceutil/trace.go:171","msg":"trace[1089531365] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000516272s","start":"2026-05-19T06:07:16.38029Z","end":"2026-05-19T06:07:18.380807Z","steps":["trace[1089531365] 'agreement among raft nodes before linearized reading' (duration: 2.000348508s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:18.380892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:16.380272Z","time spent":"2.000610834s","remote":"127.0.0.1:50720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T06:07:18.445765Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859048591,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T06:07:18.871417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:13.872536Z","time spent":"4.998869015s","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/19 06:07:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T06:07:18.934793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999896661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-19T06:07:18.934861Z","caller":"traceutil/trace.go:171","msg":"trace[467076370] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"5.000002454s","start":"2026-05-19T06:07:13.93484Z","end":"2026-05-19T06:07:18.934843Z","steps":["trace[467076370] 'agreement among raft nodes before linearized reading' (duration: 4.999914802s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:18.934907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:13.934824Z","time spent":"5.000073426s","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/05/19 06:07:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T06:07:18.946339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954804859048591,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T06:07:19.032471Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.159813616s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T06:07:19.038176Z","caller":"traceutil/trace.go:171","msg":"trace[1185132683] transaction","detail":"{read_only:false; response_revision:3211; number_of_response:1; }","duration":"4.890364983s","start":"2026-05-19T06:07:14.147794Z","end":"2026-05-19T06:07:19.038159Z","steps":["trace[1185132683] 'process raft request' (duration: 4.89022541s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.039854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:14.147767Z","time spent":"4.891963535s","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T06:07:19.038515Z","caller":"traceutil/trace.go:171","msg":"trace[654130178] transaction","detail":"{read_only:false; response_revision:3212; number_of_response:1; }","duration":"4.791613115s","start":"2026-05-19T06:07:14.246891Z","end":"2026-05-19T06:07:19.038504Z","steps":["trace[654130178] 'process raft request' (duration: 4.791241496s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:07:19.038513Z","caller":"traceutil/trace.go:171","msg":"trace[72800643] linearizableReadLoop","detail":"{readStateIndex:3337; appliedIndex:3330; }","duration":"5.103634119s","start":"2026-05-19T06:07:13.934866Z","end":"2026-05-19T06:07:19.0385Z","steps":["trace[72800643] 'read index received' (duration: 5.09792487s)","trace[72800643] 'applied index is now lower than readState.Index' (duration: 5.708589ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:07:19.038562Z","caller":"traceutil/trace.go:171","msg":"trace[828757196] transaction","detail":"{read_only:false; response_revision:3213; number_of_response:1; }","duration":"3.374852199s","start":"2026-05-19T06:07:15.663689Z","end":"2026-05-19T06:07:19.038542Z","steps":["trace[828757196] 'process raft request' (duration: 3.374591132s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.040364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:15.663668Z","time spent":"3.376659657s","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:07:19.040444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.64314106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-19T06:07:19.038589Z","caller":"traceutil/trace.go:171","msg":"trace[337204070] transaction","detail":"{read_only:false; response_revision:3214; number_of_response:1; }","duration":"3.30013762s","start":"2026-05-19T06:07:15.738444Z","end":"2026-05-19T06:07:19.038582Z","steps":["trace[337204070] 'process raft request' (duration: 3.299935495s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:07:19.040489Z","caller":"traceutil/trace.go:171","msg":"trace[2053804311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3216; }","duration":"4.643225523s","start":"2026-05-19T06:07:14.397251Z","end":"2026-05-19T06:07:19.040476Z","steps":["trace[2053804311] 'agreement among raft nodes before linearized reading' (duration: 4.64310627s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.040515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:14.397236Z","time spent":"4.643273993s","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T06:07:19.040517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.887195231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T06:07:19.040546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.180579909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-05-19T06:07:19.04061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.233413848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T06:07:19.04062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.310966282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T06:07:19.040646Z","caller":"traceutil/trace.go:171","msg":"trace[1008588434] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:3216; }","duration":"4.233491989s","start":"2026-05-19T06:07:14.807142Z","end":"2026-05-19T06:07:19.040634Z","steps":["trace[1008588434] 'agreement among raft nodes before linearized reading' (duration: 4.233438118s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:07:19.040654Z","caller":"traceutil/trace.go:171","msg":"trace[286669042] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:3216; }","duration":"3.311042494s","start":"2026-05-19T06:07:15.729604Z","end":"2026-05-19T06:07:19.040647Z","steps":["trace[286669042] 'agreement among raft nodes before linearized reading' (duration: 3.310984952s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:07:19.038607Z","caller":"traceutil/trace.go:171","msg":"trace[1474285364] transaction","detail":"{read_only:false; response_revision:3215; number_of_response:1; }","duration":"1.503454718s","start":"2026-05-19T06:07:17.535146Z","end":"2026-05-19T06:07:19.038601Z","steps":["trace[1474285364] 'process raft request' (duration: 1.503274964s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:07:19.040623Z","caller":"traceutil/trace.go:171","msg":"trace[914276439] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3216; }","duration":"3.180743404s","start":"2026-05-19T06:07:15.859866Z","end":"2026-05-19T06:07:19.040609Z","steps":["trace[914276439] 'agreement among raft nodes before linearized reading' (duration: 3.180487597s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:07:19.038611Z","caller":"traceutil/trace.go:171","msg":"trace[1920777200] transaction","detail":"{read_only:false; response_revision:3216; number_of_response:1; }","duration":"647.923409ms","start":"2026-05-19T06:07:18.390681Z","end":"2026-05-19T06:07:19.038604Z","steps":["trace[1920777200] 'process raft request' (duration: 647.770796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.041188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:15.859847Z","time spent":"3.180868646s","remote":"127.0.0.1:50876","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-19T06:07:19.041349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.823078036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T06:07:19.04135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.660543435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-19T06:07:19.041484Z","caller":"traceutil/trace.go:171","msg":"trace[1837608727] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:3216; }","duration":"4.823316112s","start":"2026-05-19T06:07:14.218122Z","end":"2026-05-19T06:07:19.041438Z","steps":["trace[1837608727] 'agreement among raft nodes before linearized reading' (duration: 4.82244459s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.041549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.103820355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-19T06:07:19.041549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:14.2181Z","time spent":"4.823435217s","remote":"127.0.0.1:42058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-05-19T06:07:19.041549Z","caller":"traceutil/trace.go:171","msg":"trace[1876992039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3216; }","duration":"4.660742451s","start":"2026-05-19T06:07:14.380752Z","end":"2026-05-19T06:07:19.041495Z","steps":["trace[1876992039] 'agreement among raft nodes before linearized reading' (duration: 4.659944639s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:07:19.04159Z","caller":"traceutil/trace.go:171","msg":"trace[259923366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3216; }","duration":"5.103869776s","start":"2026-05-19T06:07:13.937702Z","end":"2026-05-19T06:07:19.041572Z","steps":["trace[259923366] 'agreement among raft nodes before linearized reading' (duration: 5.102653194s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.041614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:13.937693Z","time spent":"5.103916096s","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T06:07:19.041616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:14.380737Z","time spent":"4.660862353s","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T06:07:19.041472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:14.246867Z","time spent":"4.79331372s","remote":"127.0.0.1:50956","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-19T06:07:19.041779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:15.738424Z","time spent":"3.302077491s","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:07:19.042056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0567944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T06:07:19.042094Z","caller":"traceutil/trace.go:171","msg":"trace[1682905198] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:3216; }","duration":"1.056864322s","start":"2026-05-19T06:07:17.985215Z","end":"2026-05-19T06:07:19.042079Z","steps":["trace[1682905198] 'agreement among raft nodes before linearized reading' (duration: 1.056746779s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.042074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.93011062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T06:07:19.042118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:17.985195Z","time spent":"1.056917894s","remote":"127.0.0.1:50748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-05-19T06:07:19.040665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:14.807118Z","time spent":"4.23354193s","remote":"127.0.0.1:57252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-05-19T06:07:19.042153Z","caller":"traceutil/trace.go:171","msg":"trace[1973593699] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:3216; }","duration":"4.930224633s","start":"2026-05-19T06:07:14.111898Z","end":"2026-05-19T06:07:19.042123Z","steps":["trace[1973593699] 'agreement among raft nodes before linearized reading' (duration: 4.929860464s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.042192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:14.111872Z","time spent":"4.930311325s","remote":"127.0.0.1:50794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-19T06:07:19.042272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401076447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:07:19.042348Z","caller":"traceutil/trace.go:171","msg":"trace[1033203822] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:3216; }","duration":"1.40118669s","start":"2026-05-19T06:07:17.641143Z","end":"2026-05-19T06:07:19.042329Z","steps":["trace[1033203822] 'agreement among raft nodes before linearized reading' (duration: 1.400901152s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.042368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.560669883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T06:07:19.042426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:17.641124Z","time spent":"1.401267542s","remote":"127.0.0.1:44400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"info","ts":"2026-05-19T06:07:19.042439Z","caller":"traceutil/trace.go:171","msg":"trace[1365102381] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:3216; }","duration":"1.560765865s","start":"2026-05-19T06:07:17.481641Z","end":"2026-05-19T06:07:19.042407Z","steps":["trace[1365102381] 'agreement among raft nodes before linearized reading' (duration: 1.560677833s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.042442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258501466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T06:07:19.042479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:17.481623Z","time spent":"1.560845187s","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T06:07:19.042503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.536739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T06:07:19.04254Z","caller":"traceutil/trace.go:171","msg":"trace[749632443] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:3216; }","duration":"878.56444ms","start":"2026-05-19T06:07:18.163957Z","end":"2026-05-19T06:07:19.042521Z","steps":["trace[749632443] 'agreement among raft nodes before linearized reading' (duration: 878.447257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.04257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:18.163913Z","time spent":"878.649922ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":22,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-05-19T06:07:19.042622Z","caller":"traceutil/trace.go:171","msg":"trace[2032526223] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:3216; }","duration":"1.258607899s","start":"2026-05-19T06:07:17.783899Z","end":"2026-05-19T06:07:19.042507Z","steps":["trace[2032526223] 'agreement among raft nodes before linearized reading' (duration: 1.258263689s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.042677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:17.783887Z","time spent":"1.258777703s","remote":"127.0.0.1:44364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-05-19T06:07:19.042075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34605421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:07:19.042796Z","caller":"traceutil/trace.go:171","msg":"trace[821856787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3216; }","duration":"1.3468091s","start":"2026-05-19T06:07:17.69598Z","end":"2026-05-19T06:07:19.042789Z","steps":["trace[821856787] 'agreement among raft nodes before linearized reading' (duration: 1.34604932s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.042823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:17.695966Z","time spent":"1.34684911s","remote":"127.0.0.1:50716","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-19T06:07:19.04302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.506043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-19T06:07:19.043075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.682457697s","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-19T06:07:19.043131Z","caller":"traceutil/trace.go:171","msg":"trace[1415589730] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3216; }","duration":"4.682537979s","start":"2026-05-19T06:07:14.360561Z","end":"2026-05-19T06:07:19.043099Z","steps":["trace[1415589730] 'agreement among raft nodes before linearized reading' (duration: 4.679826878s)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:07:19.043131Z","caller":"traceutil/trace.go:171","msg":"trace[1803627871] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3216; }","duration":"169.660578ms","start":"2026-05-19T06:07:18.873433Z","end":"2026-05-19T06:07:19.043093Z","steps":["trace[1803627871] 'agreement among raft nodes before linearized reading' (duration: 169.226696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.043177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:14.360544Z","time spent":"4.682623512s","remote":"127.0.0.1:50870","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":"warn","ts":"2026-05-19T06:07:19.042605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.989183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:07:19.04342Z","caller":"traceutil/trace.go:171","msg":"trace[1199841224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3216; }","duration":"650.880257ms","start":"2026-05-19T06:07:18.392517Z","end":"2026-05-19T06:07:19.043397Z","steps":["trace[1199841224] 'agreement among raft nodes before linearized reading' (duration: 649.83458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.043462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:18.392499Z","time spent":"650.95074ms","remote":"127.0.0.1:50720","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-19T06:07:19.040569Z","caller":"traceutil/trace.go:171","msg":"trace[327933741] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:3216; }","duration":"3.887292563s","start":"2026-05-19T06:07:15.153264Z","end":"2026-05-19T06:07:19.040557Z","steps":["trace[327933741] 'agreement among raft nodes before linearized reading' (duration: 3.88717366s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.043602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:15.153242Z","time spent":"3.890347272s","remote":"127.0.0.1:51150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":11,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-19T06:07:19.043622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:17.535127Z","time spent":"1.505562843s","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:07:19.040686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:15.729582Z","time spent":"3.311096296s","remote":"127.0.0.1:50854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":2,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T06:07:19.043732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.644028465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T06:07:19.044164Z","caller":"traceutil/trace.go:171","msg":"trace[1737918083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3216; }","duration":"4.644506736s","start":"2026-05-19T06:07:14.399646Z","end":"2026-05-19T06:07:19.044152Z","steps":["trace[1737918083] 'agreement among raft nodes before linearized reading' (duration: 4.641029795s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:07:19.044202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:14.399633Z","time spent":"4.644557407s","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T06:07:19.044461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:07:18.390654Z","time spent":"650.069336ms","remote":"127.0.0.1:50790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T06:08:41.793259Z","caller":"traceutil/trace.go:171","msg":"trace[1556475873] transaction","detail":"{read_only:false; response_revision:3839; number_of_response:1; }","duration":"473.700359ms","start":"2026-05-19T06:08:41.319524Z","end":"2026-05-19T06:08:41.793224Z","steps":["trace[1556475873] 'process raft request' (duration: 473.496584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:08:41.793486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:08:41.319493Z","time spent":"473.874623ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T06:08:41.813116Z","caller":"traceutil/trace.go:171","msg":"trace[842303942] transaction","detail":"{read_only:false; response_revision:3841; number_of_response:1; }","duration":"486.547954ms","start":"2026-05-19T06:08:41.326538Z","end":"2026-05-19T06:08:41.813086Z","steps":["trace[842303942] 'process raft request' (duration: 486.457571ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:08:41.813151Z","caller":"traceutil/trace.go:171","msg":"trace[981907869] transaction","detail":"{read_only:false; response_revision:3840; number_of_response:1; }","duration":"491.070832ms","start":"2026-05-19T06:08:41.322041Z","end":"2026-05-19T06:08:41.813111Z","steps":["trace[981907869] 'process raft request' (duration: 490.770744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:08:41.813319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:08:41.326515Z","time spent":"486.694588ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T06:08:41.813351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:08:41.322Z","time spent":"491.249147ms","remote":"127.0.0.1:50956","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-19T06:08:41.813351Z","caller":"traceutil/trace.go:171","msg":"trace[936662413] transaction","detail":"{read_only:false; response_revision:3842; number_of_response:1; }","duration":"485.751023ms","start":"2026-05-19T06:08:41.327566Z","end":"2026-05-19T06:08:41.813317Z","steps":["trace[936662413] 'process raft request' (duration: 485.471626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:08:41.813527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:08:41.327559Z","time spent":"485.878236ms","remote":"127.0.0.1:50956","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-19T06:08:41.813755Z","caller":"traceutil/trace.go:171","msg":"trace[1155650014] transaction","detail":"{read_only:false; response_revision:3843; number_of_response:1; }","duration":"191.340915ms","start":"2026-05-19T06:08:41.622397Z","end":"2026-05-19T06:08:41.813738Z","steps":["trace[1155650014] 'process raft request' (duration: 190.883922ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:08:41.813771Z","caller":"traceutil/trace.go:171","msg":"trace[731515246] linearizableReadLoop","detail":"{readStateIndex:3986; appliedIndex:3982; }","duration":"480.070135ms","start":"2026-05-19T06:08:41.333674Z","end":"2026-05-19T06:08:41.813744Z","steps":["trace[731515246] 'read index received' (duration: 459.353225ms)","trace[731515246] 'applied index is now lower than readState.Index' (duration: 20.684899ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:08:41.813992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.323352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T06:08:41.814049Z","caller":"traceutil/trace.go:171","msg":"trace[1264553809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3843; }","duration":"480.423524ms","start":"2026-05-19T06:08:41.333612Z","end":"2026-05-19T06:08:41.814036Z","steps":["trace[1264553809] 'agreement among raft nodes before linearized reading' (duration: 480.24077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:08:41.814083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:08:41.333595Z","time spent":"480.481755ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T06:08:41.814169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.257022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:08:41.814242Z","caller":"traceutil/trace.go:171","msg":"trace[330351590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3843; }","duration":"434.384555ms","start":"2026-05-19T06:08:41.379844Z","end":"2026-05-19T06:08:41.814229Z","steps":["trace[330351590] 'agreement among raft nodes before linearized reading' (duration: 434.246911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:08:41.814302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:08:41.379827Z","time spent":"434.441866ms","remote":"127.0.0.1:50716","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-19T06:08:41.814299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.279941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-19T06:08:41.814302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.805296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-19T06:08:41.814345Z","caller":"traceutil/trace.go:171","msg":"trace[2109937073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3843; }","duration":"338.353533ms","start":"2026-05-19T06:08:41.47598Z","end":"2026-05-19T06:08:41.814334Z","steps":["trace[2109937073] 'agreement among raft nodes before linearized reading' (duration: 338.20397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:08:41.814378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:08:41.475964Z","time spent":"338.407195ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T06:08:41.814368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.737846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T06:08:41.814381Z","caller":"traceutil/trace.go:171","msg":"trace[1260621050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3843; }","duration":"343.907639ms","start":"2026-05-19T06:08:41.470454Z","end":"2026-05-19T06:08:41.814362Z","steps":["trace[1260621050] 'agreement among raft nodes before linearized reading' (duration: 343.715333ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:08:41.814416Z","caller":"traceutil/trace.go:171","msg":"trace[1694744854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3843; }","duration":"345.824538ms","start":"2026-05-19T06:08:41.468578Z","end":"2026-05-19T06:08:41.814403Z","steps":["trace[1694744854] 'agreement among raft nodes before linearized reading' (duration: 345.644003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:08:41.81441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.964331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-19T06:08:41.814433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:08:41.470447Z","time spent":"343.976471ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T06:08:41.814453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:08:41.46856Z","time spent":"345.88542ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T06:08:41.814492Z","caller":"traceutil/trace.go:171","msg":"trace[1838578440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3843; }","duration":"344.085864ms","start":"2026-05-19T06:08:41.470388Z","end":"2026-05-19T06:08:41.814474Z","steps":["trace[1838578440] 'agreement among raft nodes before linearized reading' (duration: 343.812686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:08:41.814538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:08:41.470376Z","time spent":"344.153355ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T06:09:16.948523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.605373ms","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-19T06:09:16.948639Z","caller":"traceutil/trace.go:171","msg":"trace[1349989518] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4105; }","duration":"608.809679ms","start":"2026-05-19T06:09:16.339807Z","end":"2026-05-19T06:09:16.948616Z","steps":["trace[1349989518] 'range keys from in-memory index tree' (duration: 608.319717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:09:16.948702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:09:16.339765Z","time spent":"608.927272ms","remote":"127.0.0.1:50870","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":"warn","ts":"2026-05-19T06:09:16.948727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.600974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-haproxy\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:09:16.94882Z","caller":"traceutil/trace.go:171","msg":"trace[735853872] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-haproxy; range_end:; response_count:0; response_revision:4105; }","duration":"183.755688ms","start":"2026-05-19T06:09:16.765041Z","end":"2026-05-19T06:09:16.948796Z","steps":["trace[735853872] 'range keys from in-memory index tree' (duration: 183.493229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:09:16.949104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.855777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2db6g\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-05-19T06:09:16.949182Z","caller":"traceutil/trace.go:171","msg":"trace[1939645890] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2db6g; range_end:; response_count:1; response_revision:4105; }","duration":"300.97245ms","start":"2026-05-19T06:09:16.648191Z","end":"2026-05-19T06:09:16.949163Z","steps":["trace[1939645890] 'range keys from in-memory index tree' (duration: 299.969273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:09:16.94923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:09:16.64817Z","time spent":"301.044972ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6470,"request content":"key:\"/registry/pods/openstack/keepalived-2db6g\" "} {"level":"info","ts":"2026-05-19T06:09:55.257393Z","caller":"traceutil/trace.go:171","msg":"trace[1694057014] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"111.42744ms","start":"2026-05-19T06:09:55.14594Z","end":"2026-05-19T06:09:55.257368Z","steps":["trace[1694057014] 'process raft request' (duration: 111.150213ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:09:58.456055Z","caller":"traceutil/trace.go:171","msg":"trace[278469038] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"152.716274ms","start":"2026-05-19T06:09:58.303291Z","end":"2026-05-19T06:09:58.456008Z","steps":["trace[278469038] 'process raft request' (duration: 152.55109ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:10:07.445326Z","caller":"traceutil/trace.go:171","msg":"trace[610330911] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"201.535566ms","start":"2026-05-19T06:10:07.243731Z","end":"2026-05-19T06:10:07.445267Z","steps":["trace[610330911] 'process raft request' (duration: 201.353322ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:10:07.4804Z","caller":"traceutil/trace.go:171","msg":"trace[414006240] linearizableReadLoop","detail":"{readStateIndex:5252; appliedIndex:5251; }","duration":"230.683214ms","start":"2026-05-19T06:10:07.249694Z","end":"2026-05-19T06:10:07.480377Z","steps":["trace[414006240] 'read index received' (duration: 196.596776ms)","trace[414006240] 'applied index is now lower than readState.Index' (duration: 34.085198ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:10:07.480489Z","caller":"traceutil/trace.go:171","msg":"trace[354874892] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"231.437293ms","start":"2026-05-19T06:10:07.249Z","end":"2026-05-19T06:10:07.480438Z","steps":["trace[354874892] 'process raft request' (duration: 231.266939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:10:07.480669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.02499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-19T06:10:07.480719Z","caller":"traceutil/trace.go:171","msg":"trace[504573648] 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:5061; }","duration":"189.091843ms","start":"2026-05-19T06:10:07.291615Z","end":"2026-05-19T06:10:07.480706Z","steps":["trace[504573648] 'agreement among raft nodes before linearized reading' (duration: 188.913037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:10:07.481097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.063883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2db6g\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-05-19T06:10:07.481259Z","caller":"traceutil/trace.go:171","msg":"trace[608683181] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2db6g; range_end:; response_count:1; response_revision:5061; }","duration":"138.297219ms","start":"2026-05-19T06:10:07.342938Z","end":"2026-05-19T06:10:07.481236Z","steps":["trace[608683181] 'agreement among raft nodes before linearized reading' (duration: 137.696703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:10:07.481438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.724671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-05-19T06:10:07.481526Z","caller":"traceutil/trace.go:171","msg":"trace[300920982] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5061; }","duration":"231.824933ms","start":"2026-05-19T06:10:07.24968Z","end":"2026-05-19T06:10:07.481504Z","steps":["trace[300920982] 'agreement among raft nodes before linearized reading' (duration: 230.855788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:10:15.306085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.72559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T06:10:15.306219Z","caller":"traceutil/trace.go:171","msg":"trace[1345681758] linearizableReadLoop","detail":"{readStateIndex:5422; appliedIndex:5421; }","duration":"172.292594ms","start":"2026-05-19T06:10:15.133908Z","end":"2026-05-19T06:10:15.306201Z","steps":["trace[1345681758] 'read index received' (duration: 96.292µs)","trace[1345681758] 'applied index is now lower than readState.Index' (duration: 172.178181ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:10:15.306259Z","caller":"traceutil/trace.go:171","msg":"trace[102801056] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"257.547813ms","start":"2026-05-19T06:10:15.048674Z","end":"2026-05-19T06:10:15.306222Z","steps":["trace[102801056] 'process raft request' (duration: 65.615228ms)","trace[102801056] 'compare' (duration: 191.534255ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:10:15.306671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.765647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T06:10:15.306726Z","caller":"traceutil/trace.go:171","msg":"trace[658390258] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5225; }","duration":"172.825898ms","start":"2026-05-19T06:10:15.133886Z","end":"2026-05-19T06:10:15.306712Z","steps":["trace[658390258] 'agreement among raft nodes before linearized reading' (duration: 172.364996ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:10:15.515707Z","caller":"traceutil/trace.go:171","msg":"trace[563065866] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"125.068775ms","start":"2026-05-19T06:10:15.390559Z","end":"2026-05-19T06:10:15.515627Z","steps":["trace[563065866] 'process raft request' (duration: 68.964285ms)","trace[563065866] 'compare' (duration: 55.886625ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:10:15.51586Z","caller":"traceutil/trace.go:171","msg":"trace[1789142575] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"124.678645ms","start":"2026-05-19T06:10:15.391147Z","end":"2026-05-19T06:10:15.515826Z","steps":["trace[1789142575] 'process raft request' (duration: 124.411767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:10:23.505758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.821923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-19T06:10:23.505835Z","caller":"traceutil/trace.go:171","msg":"trace[2014350276] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5392; }","duration":"314.942496ms","start":"2026-05-19T06:10:23.190877Z","end":"2026-05-19T06:10:23.505819Z","steps":["trace[2014350276] 'range keys from in-memory index tree' (duration: 314.639709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:10:23.505863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:10:23.190849Z","time spent":"315.006897ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-19T06:10:23.539833Z","caller":"traceutil/trace.go:171","msg":"trace[663832575] linearizableReadLoop","detail":"{readStateIndex:5595; appliedIndex:5594; }","duration":"279.992207ms","start":"2026-05-19T06:10:23.259816Z","end":"2026-05-19T06:10:23.539808Z","steps":["trace[663832575] 'read index received' (duration: 220.001015ms)","trace[663832575] 'applied index is now lower than readState.Index' (duration: 59.989222ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T06:10:23.540024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.219633ms","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-19T06:10:23.540092Z","caller":"traceutil/trace.go:171","msg":"trace[204844400] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5392; }","duration":"280.298975ms","start":"2026-05-19T06:10:23.25978Z","end":"2026-05-19T06:10:23.540079Z","steps":["trace[204844400] 'agreement among raft nodes before linearized reading' (duration: 280.11904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:10:23.542447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.666017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:10:23.542524Z","caller":"traceutil/trace.go:171","msg":"trace[248025381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5393; }","duration":"161.801251ms","start":"2026-05-19T06:10:23.380704Z","end":"2026-05-19T06:10:23.542506Z","steps":["trace[248025381] 'agreement among raft nodes before linearized reading' (duration: 161.657687ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:10:30.322429Z","caller":"traceutil/trace.go:171","msg":"trace[1579927153] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"204.662336ms","start":"2026-05-19T06:10:30.11775Z","end":"2026-05-19T06:10:30.322412Z","steps":["trace[1579927153] 'process raft request' (duration: 204.517283ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:10:30.32556Z","caller":"traceutil/trace.go:171","msg":"trace[2131653383] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"152.035318ms","start":"2026-05-19T06:10:30.173501Z","end":"2026-05-19T06:10:30.325536Z","steps":["trace[2131653383] 'process raft request' (duration: 151.872393ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:10:30.325904Z","caller":"traceutil/trace.go:171","msg":"trace[1235254691] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"148.917465ms","start":"2026-05-19T06:10:30.176962Z","end":"2026-05-19T06:10:30.325879Z","steps":["trace[1235254691] 'process raft request' (duration: 148.530746ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:10:30.326247Z","caller":"traceutil/trace.go:171","msg":"trace[26779916] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"147.299623ms","start":"2026-05-19T06:10:30.178935Z","end":"2026-05-19T06:10:30.326235Z","steps":["trace[26779916] 'process raft request' (duration: 147.248531ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:10:30.326368Z","caller":"traceutil/trace.go:171","msg":"trace[1746803480] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"148.429202ms","start":"2026-05-19T06:10:30.177928Z","end":"2026-05-19T06:10:30.326357Z","steps":["trace[1746803480] 'process raft request' (duration: 147.853297ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:11:34.915575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3003} {"level":"info","ts":"2026-05-19T06:11:35.008656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3003,"took":"91.335809ms","hash":919718213,"current-db-size-bytes":56315904,"current-db-size":"56 MB","current-db-size-in-use-bytes":31227904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T06:11:35.008741Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":919718213,"revision":3003,"compact-revision":-1} {"level":"info","ts":"2026-05-19T06:14:08.244829Z","caller":"traceutil/trace.go:171","msg":"trace[926621642] linearizableReadLoop","detail":"{readStateIndex:7264; appliedIndex:7263; }","duration":"100.124736ms","start":"2026-05-19T06:14:08.144676Z","end":"2026-05-19T06:14:08.244801Z","steps":["trace[926621642] 'read index received' (duration: 99.575722ms)","trace[926621642] 'applied index is now lower than readState.Index' (duration: 547.874µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:14:08.244927Z","caller":"traceutil/trace.go:171","msg":"trace[1477763993] transaction","detail":"{read_only:false; response_revision:7008; number_of_response:1; }","duration":"101.885602ms","start":"2026-05-19T06:14:08.143007Z","end":"2026-05-19T06:14:08.244893Z","steps":["trace[1477763993] 'process raft request' (duration: 101.40493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:14:08.244961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.27097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T06:14:08.245262Z","caller":"traceutil/trace.go:171","msg":"trace[738028111] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7008; }","duration":"100.574467ms","start":"2026-05-19T06:14:08.144641Z","end":"2026-05-19T06:14:08.245215Z","steps":["trace[738028111] 'agreement among raft nodes before linearized reading' (duration: 100.26764ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T06:14:24.20233Z","caller":"traceutil/trace.go:171","msg":"trace[876804223] linearizableReadLoop","detail":"{readStateIndex:7344; appliedIndex:7343; }","duration":"396.130669ms","start":"2026-05-19T06:14:23.806173Z","end":"2026-05-19T06:14:24.202303Z","steps":["trace[876804223] 'read index received' (duration: 393.87943ms)","trace[876804223] 'applied index is now lower than readState.Index' (duration: 2.250149ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T06:14:24.202422Z","caller":"traceutil/trace.go:171","msg":"trace[180543676] transaction","detail":"{read_only:false; response_revision:7085; number_of_response:1; }","duration":"484.393323ms","start":"2026-05-19T06:14:23.717971Z","end":"2026-05-19T06:14:24.202364Z","steps":["trace[180543676] 'process raft request' (duration: 482.085913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:14:24.20273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.543861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-19T06:14:24.202818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:14:23.717953Z","time spent":"484.767813ms","remote":"127.0.0.1:50956","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-19T06:14:24.202853Z","caller":"traceutil/trace.go:171","msg":"trace[2080552053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7085; }","duration":"396.725475ms","start":"2026-05-19T06:14:23.806104Z","end":"2026-05-19T06:14:24.202829Z","steps":["trace[2080552053] 'agreement among raft nodes before linearized reading' (duration: 396.415256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:14:24.202896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.273884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-19T06:14:24.20292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:14:23.806071Z","time spent":"396.836378ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T06:14:24.202944Z","caller":"traceutil/trace.go:171","msg":"trace[2015911280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7085; }","duration":"340.378677ms","start":"2026-05-19T06:14:23.862554Z","end":"2026-05-19T06:14:24.202933Z","steps":["trace[2015911280] 'agreement among raft nodes before linearized reading' (duration: 340.205762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:14:24.202985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:14:23.862534Z","time spent":"340.443089ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T06:14:24.203146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.346444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-19T06:14:24.20318Z","caller":"traceutil/trace.go:171","msg":"trace[2075992376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7085; }","duration":"338.404594ms","start":"2026-05-19T06:14:23.864767Z","end":"2026-05-19T06:14:24.203172Z","steps":["trace[2075992376] 'agreement among raft nodes before linearized reading' (duration: 338.317392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:14:24.203208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:14:23.864754Z","time spent":"338.446346ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T06:14:24.203553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.695981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T06:14:24.203745Z","caller":"traceutil/trace.go:171","msg":"trace[757465995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7085; }","duration":"328.972228ms","start":"2026-05-19T06:14:23.874745Z","end":"2026-05-19T06:14:24.203717Z","steps":["trace[757465995] 'agreement among raft nodes before linearized reading' (duration: 328.220538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T06:14:24.203856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T06:14:23.874737Z","time spent":"329.100991ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T06:16:34.93391Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6072} {"level":"info","ts":"2026-05-19T06:16:35.03606Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6072,"took":"99.518869ms","hash":4246484404,"current-db-size-bytes":56315904,"current-db-size":"56 MB","current-db-size-in-use-bytes":18362368,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-19T06:16:35.03613Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4246484404,"revision":6072,"compact-revision":3003}