{"level":"warn","ts":"2026-07-24T21:24:44.580448Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T21:24:44.580578Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-07-24T21:24:44.580727Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T21:24:44.580748Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-07-24T21:24:44.580774Z","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-07-24T21:24:44.581885Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-07-24T21:24:44.581997Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-07-24T21:24:44.586267Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.96709ms"} {"level":"info","ts":"2026-07-24T21:24:44.596393Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-07-24T21:24:44.59649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T21:24:44.596516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-07-24T21:24:44.596533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T21:24:44.596578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-07-24T21:24:44.596642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-07-24T21:24:44.603942Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T21:24:44.605859Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T21:24:44.606838Z","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-07-24T21:24:44.609995Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T21:24:44.610095Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T21:24:44.610286Z","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-07-24T21:24:44.610383Z","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-07-24T21:24:44.610394Z","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-07-24T21:24:44.611507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-07-24T21:24:44.611754Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-07-24T21:24:44.616858Z","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-07-24T21:24:44.617122Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-07-24T21:24:44.617137Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T21:24:44.617161Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-07-24T21:24:44.617182Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T21:24:45.397783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T21:24:45.397875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T21:24:45.397921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-07-24T21:24:45.397942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-07-24T21:24:45.397948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-07-24T21:24:45.39796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-07-24T21:24:45.397972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-07-24T21:24:45.399289Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:24:45.400142Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T21:24:45.400156Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T21:24:45.400378Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T21:24:45.400577Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:24:45.400714Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:24:45.400745Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T21:24:45.400742Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T21:24:45.400766Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T21:24:45.403222Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T21:24:45.410997Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-07-24T21:24:54.948333Z","caller":"traceutil/trace.go:171","msg":"trace[617591389] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"147.897431ms","start":"2026-07-24T21:24:54.800409Z","end":"2026-07-24T21:24:54.948306Z","steps":["trace[617591389] 'process raft request' (duration: 147.721703ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:24:54.957484Z","caller":"traceutil/trace.go:171","msg":"trace[720023155] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:310; }","duration":"118.078956ms","start":"2026-07-24T21:24:54.839385Z","end":"2026-07-24T21:24:54.957464Z","steps":["trace[720023155] 'read index received' (duration: 108.769575ms)","trace[720023155] 'applied index is now lower than readState.Index' (duration: 9.308291ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:24:54.957543Z","caller":"traceutil/trace.go:171","msg":"trace[1868360090] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"156.918689ms","start":"2026-07-24T21:24:54.800599Z","end":"2026-07-24T21:24:54.957518Z","steps":["trace[1868360090] 'process raft request' (duration: 156.808855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:24:54.957662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.256104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-24T21:24:54.957722Z","caller":"traceutil/trace.go:171","msg":"trace[885120384] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:302; }","duration":"118.358169ms","start":"2026-07-24T21:24:54.839354Z","end":"2026-07-24T21:24:54.957712Z","steps":["trace[885120384] 'agreement among raft nodes before linearized reading' (duration: 118.225463ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:24:54.957539Z","caller":"traceutil/trace.go:171","msg":"trace[2125326905] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"157.091377ms","start":"2026-07-24T21:24:54.800409Z","end":"2026-07-24T21:24:54.9575Z","steps":["trace[2125326905] 'process raft request' (duration: 156.867187ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:24:55.964142Z","caller":"traceutil/trace.go:171","msg":"trace[1952771585] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"158.474731ms","start":"2026-07-24T21:24:55.805618Z","end":"2026-07-24T21:24:55.964092Z","steps":["trace[1952771585] 'process raft request' (duration: 97.21998ms)","trace[1952771585] 'compare' (duration: 60.950558ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:24:55.964408Z","caller":"traceutil/trace.go:171","msg":"trace[206679123] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"104.570408ms","start":"2026-07-24T21:24:55.859799Z","end":"2026-07-24T21:24:55.96437Z","steps":["trace[206679123] 'process raft request' (duration: 104.529616ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:24:55.964479Z","caller":"traceutil/trace.go:171","msg":"trace[1866663706] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:342; }","duration":"107.930972ms","start":"2026-07-24T21:24:55.856525Z","end":"2026-07-24T21:24:55.964456Z","steps":["trace[1866663706] 'read index received' (duration: 46.385129ms)","trace[1866663706] 'applied index is now lower than readState.Index' (duration: 61.543883ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:24:55.964457Z","caller":"traceutil/trace.go:171","msg":"trace[1043639287] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"157.242398ms","start":"2026-07-24T21:24:55.807172Z","end":"2026-07-24T21:24:55.964415Z","steps":["trace[1043639287] 'process raft request' (duration: 156.979846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:24:55.964638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.099079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-mfwcr\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-07-24T21:24:55.964462Z","caller":"traceutil/trace.go:171","msg":"trace[460187330] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"155.155978ms","start":"2026-07-24T21:24:55.809268Z","end":"2026-07-24T21:24:55.964424Z","steps":["trace[460187330] 'process raft request' (duration: 154.96559ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:24:55.964695Z","caller":"traceutil/trace.go:171","msg":"trace[30459037] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-mfwcr; range_end:; response_count:1; response_revision:335; }","duration":"108.236715ms","start":"2026-07-24T21:24:55.856447Z","end":"2026-07-24T21:24:55.964683Z","steps":["trace[30459037] 'agreement among raft nodes before linearized reading' (duration: 108.085799ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:24:56.226014Z","caller":"traceutil/trace.go:171","msg":"trace[573283657] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:347; }","duration":"251.60256ms","start":"2026-07-24T21:24:55.974365Z","end":"2026-07-24T21:24:56.225968Z","steps":["trace[573283657] 'read index received' (duration: 69.539188ms)","trace[573283657] 'applied index is now lower than readState.Index' (duration: 182.062142ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:24:56.22621Z","caller":"traceutil/trace.go:171","msg":"trace[1812042436] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"251.726356ms","start":"2026-07-24T21:24:55.974452Z","end":"2026-07-24T21:24:56.226178Z","steps":["trace[1812042436] 'process raft request' (duration: 251.368511ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:24:56.226281Z","caller":"traceutil/trace.go:171","msg":"trace[193081655] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"218.42091ms","start":"2026-07-24T21:24:56.007798Z","end":"2026-07-24T21:24:56.226219Z","steps":["trace[193081655] 'process raft request' (duration: 218.092996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:24:56.226352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.953016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-07-24T21:24:56.226464Z","caller":"traceutil/trace.go:171","msg":"trace[2010088275] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:340; }","duration":"252.076171ms","start":"2026-07-24T21:24:55.97436Z","end":"2026-07-24T21:24:56.226436Z","steps":["trace[2010088275] 'agreement among raft nodes before linearized reading' (duration: 251.890373ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:24:56.226695Z","caller":"traceutil/trace.go:171","msg":"trace[493073861] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"255.436885ms","start":"2026-07-24T21:24:55.971209Z","end":"2026-07-24T21:24:56.226646Z","steps":["trace[493073861] 'process raft request' (duration: 158.662561ms)","trace[493073861] 'compare' (duration: 95.363413ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:24:56.22683Z","caller":"traceutil/trace.go:171","msg":"trace[284413009] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"252.536121ms","start":"2026-07-24T21:24:55.97424Z","end":"2026-07-24T21:24:56.226776Z","steps":["trace[284413009] 'process raft request' (duration: 251.466965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:24:56.227347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.871459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-07-24T21:24:56.227464Z","caller":"traceutil/trace.go:171","msg":"trace[1623142670] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:340; }","duration":"237.989903ms","start":"2026-07-24T21:24:55.989401Z","end":"2026-07-24T21:24:56.227391Z","steps":["trace[1623142670] 'agreement among raft nodes before linearized reading' (duration: 237.843367ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:10.203331Z","caller":"traceutil/trace.go:171","msg":"trace[644211976] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"358.038786ms","start":"2026-07-24T21:25:09.845271Z","end":"2026-07-24T21:25:10.20331Z","steps":["trace[644211976] 'process raft request' (duration: 357.902304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:10.20387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:09.845254Z","time spent":"358.145867ms","remote":"127.0.0.1:44192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:25:25.785475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369247907264645,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T21:25:26.215403Z","caller":"traceutil/trace.go:171","msg":"trace[806236411] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"957.391219ms","start":"2026-07-24T21:25:25.257943Z","end":"2026-07-24T21:25:26.215334Z","steps":["trace[806236411] 'process raft request' (duration: 908.466003ms)","trace[806236411] 'compare' (duration: 48.408746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:25:26.215674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:25.257894Z","time spent":"957.631184ms","remote":"127.0.0.1:43764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:25:26.216035Z","caller":"traceutil/trace.go:171","msg":"trace[1050547996] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"921.732723ms","start":"2026-07-24T21:25:25.294254Z","end":"2026-07-24T21:25:26.215987Z","steps":["trace[1050547996] 'process raft request' (duration: 921.065799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:26.216398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.984606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:26.216519Z","caller":"traceutil/trace.go:171","msg":"trace[1391054643] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:524; }","duration":"931.169ms","start":"2026-07-24T21:25:25.285328Z","end":"2026-07-24T21:25:26.216497Z","steps":["trace[1391054643] 'agreement among raft nodes before linearized reading' (duration: 930.801653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:26.21669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:25.285307Z","time spent":"931.304862ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-24T21:25:26.21639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:25.294211Z","time spent":"922.032229ms","remote":"127.0.0.1:43764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:25:26.216035Z","caller":"traceutil/trace.go:171","msg":"trace[738733357] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:545; }","duration":"930.628899ms","start":"2026-07-24T21:25:25.285334Z","end":"2026-07-24T21:25:26.215963Z","steps":["trace[738733357] 'read index received' (duration: 881.145851ms)","trace[738733357] 'applied index is now lower than readState.Index' (duration: 49.481158ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:26.216087Z","caller":"traceutil/trace.go:171","msg":"trace[2066248672] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"879.384691ms","start":"2026-07-24T21:25:25.336686Z","end":"2026-07-24T21:25:26.21607Z","steps":["trace[2066248672] 'process raft request' (duration: 878.949713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:26.217351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.911105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T21:25:26.217393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:25.336665Z","time spent":"880.614177ms","remote":"127.0.0.1:43764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:25:26.217465Z","caller":"traceutil/trace.go:171","msg":"trace[1893008438] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:524; }","duration":"932.060088ms","start":"2026-07-24T21:25:25.285382Z","end":"2026-07-24T21:25:26.217443Z","steps":["trace[1893008438] 'agreement among raft nodes before linearized reading' (duration: 931.889705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:26.21751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:25.285379Z","time spent":"932.119539ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-24T21:25:26.217505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.393891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T21:25:26.217577Z","caller":"traceutil/trace.go:171","msg":"trace[1952163306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"891.524173ms","start":"2026-07-24T21:25:25.326031Z","end":"2026-07-24T21:25:26.217555Z","steps":["trace[1952163306] 'agreement among raft nodes before linearized reading' (duration: 891.303199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:26.217627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:25.326005Z","time spent":"891.613955ms","remote":"127.0.0.1:43990","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-07-24T21:25:26.217635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.759406ms","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-07-24T21:25:26.217686Z","caller":"traceutil/trace.go:171","msg":"trace[1843521269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"901.873349ms","start":"2026-07-24T21:25:25.315797Z","end":"2026-07-24T21:25:26.217671Z","steps":["trace[1843521269] 'agreement among raft nodes before linearized reading' (duration: 901.730176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:26.217726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:25.315757Z","time spent":"901.959851ms","remote":"127.0.0.1:43990","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-07-24T21:25:26.217734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.059794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T21:25:26.217878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.056405ms","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-07-24T21:25:26.217931Z","caller":"traceutil/trace.go:171","msg":"trace[1289444355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"891.140836ms","start":"2026-07-24T21:25:25.326771Z","end":"2026-07-24T21:25:26.217912Z","steps":["trace[1289444355] 'agreement among raft nodes before linearized reading' (duration: 890.532123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:26.217963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:25.326743Z","time spent":"891.209627ms","remote":"127.0.0.1:43990","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":"info","ts":"2026-07-24T21:25:26.217925Z","caller":"traceutil/trace.go:171","msg":"trace[1908422769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"432.259507ms","start":"2026-07-24T21:25:25.785586Z","end":"2026-07-24T21:25:26.217845Z","steps":["trace[1908422769] 'agreement among raft nodes before linearized reading' (duration: 431.863269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:26.218048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:25.785562Z","time spent":"432.467642ms","remote":"127.0.0.1:43722","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-07-24T21:25:27.231024Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369247907264651,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T21:25:27.274122Z","caller":"traceutil/trace.go:171","msg":"trace[1621532953] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"602.291221ms","start":"2026-07-24T21:25:26.671798Z","end":"2026-07-24T21:25:27.274089Z","steps":["trace[1621532953] 'process raft request' (duration: 585.620459ms)","trace[1621532953] 'compare' (duration: 16.501518ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:25:27.2744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:26.67177Z","time spent":"602.518575ms","remote":"127.0.0.1:43764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:25:27.277755Z","caller":"traceutil/trace.go:171","msg":"trace[1217936480] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"558.988418ms","start":"2026-07-24T21:25:26.718702Z","end":"2026-07-24T21:25:27.27769Z","steps":["trace[1217936480] 'process raft request' (duration: 557.812314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:27.278042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:26.718669Z","time spent":"559.204193ms","remote":"127.0.0.1:43764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:25:27.27807Z","caller":"traceutil/trace.go:171","msg":"trace[5671590] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:576; }","duration":"547.607139ms","start":"2026-07-24T21:25:26.730362Z","end":"2026-07-24T21:25:27.277969Z","steps":["trace[5671590] 'read index received' (duration: 527.128529ms)","trace[5671590] 'applied index is now lower than readState.Index' (duration: 20.47724ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:27.278234Z","caller":"traceutil/trace.go:171","msg":"trace[2090046435] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"346.610366ms","start":"2026-07-24T21:25:26.931575Z","end":"2026-07-24T21:25:27.278185Z","steps":["trace[2090046435] 'process raft request' (duration: 345.85154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:27.278275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.883265ms","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-07-24T21:25:27.278329Z","caller":"traceutil/trace.go:171","msg":"trace[140764373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"548.013127ms","start":"2026-07-24T21:25:26.730296Z","end":"2026-07-24T21:25:27.278309Z","steps":["trace[140764373] 'agreement among raft nodes before linearized reading' (duration: 547.863914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:27.278372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:26.730286Z","time spent":"548.075778ms","remote":"127.0.0.1:43990","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-07-24T21:25:27.278384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:26.93154Z","time spent":"346.773088ms","remote":"127.0.0.1:43764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T21:25:32.914311Z","caller":"traceutil/trace.go:171","msg":"trace[629390685] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"373.725453ms","start":"2026-07-24T21:25:32.540545Z","end":"2026-07-24T21:25:32.914271Z","steps":["trace[629390685] 'process raft request' (duration: 373.516678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:32.914545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:32.540526Z","time spent":"373.890777ms","remote":"127.0.0.1:43926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:25:33.729927Z","caller":"traceutil/trace.go:171","msg":"trace[637862174] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"420.730905ms","start":"2026-07-24T21:25:33.309168Z","end":"2026-07-24T21:25:33.729899Z","steps":["trace[637862174] 'read index received' (duration: 419.970058ms)","trace[637862174] 'applied index is now lower than readState.Index' (duration: 759.777µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:33.730065Z","caller":"traceutil/trace.go:171","msg":"trace[1182536668] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.028441377s","start":"2026-07-24T21:25:32.701535Z","end":"2026-07-24T21:25:33.729976Z","steps":["trace[1182536668] 'process raft request' (duration: 1.027796022s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:33.730221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.020982ms","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-07-24T21:25:33.730263Z","caller":"traceutil/trace.go:171","msg":"trace[1934713792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"421.190256ms","start":"2026-07-24T21:25:33.30906Z","end":"2026-07-24T21:25:33.73025Z","steps":["trace[1934713792] 'agreement among raft nodes before linearized reading' (duration: 420.94247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:33.730302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:33.309023Z","time spent":"421.268788ms","remote":"127.0.0.1:43990","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-07-24T21:25:33.730308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:32.701493Z","time spent":"1.028683882s","remote":"127.0.0.1:43826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:25:34.28565Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369247907264795,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T21:25:34.786863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369247907264795,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T21:25:34.858198Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.10322896s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-24T21:25:34.898664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143410986s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T21:25:34.89908Z","caller":"traceutil/trace.go:171","msg":"trace[1266373625] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.162118783s","start":"2026-07-24T21:25:33.736916Z","end":"2026-07-24T21:25:34.899035Z","steps":["trace[1266373625] 'process raft request' (duration: 1.161872547s)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:34.899303Z","caller":"traceutil/trace.go:171","msg":"trace[1600375515] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:626; }","duration":"1.114314878s","start":"2026-07-24T21:25:33.784956Z","end":"2026-07-24T21:25:34.899271Z","steps":["trace[1600375515] 'read index received' (duration: 1.073440096s)","trace[1600375515] 'applied index is now lower than readState.Index' (duration: 40.872612ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:25:34.899364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:33.736887Z","time spent":"1.162299626s","remote":"127.0.0.1:43990","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-07-24T21:25:34.899396Z","caller":"traceutil/trace.go:171","msg":"trace[275397567] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.105101093s","start":"2026-07-24T21:25:33.794271Z","end":"2026-07-24T21:25:34.899372Z","steps":["trace[275397567] 'process raft request' (duration: 1.104673103s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:34.899537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:33.794241Z","time spent":"1.105225396s","remote":"127.0.0.1:44248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T21:25:34.899633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114629005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T21:25:34.89978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.344676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T21:25:34.899809Z","caller":"traceutil/trace.go:171","msg":"trace[235922808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"1.11487545s","start":"2026-07-24T21:25:33.784896Z","end":"2026-07-24T21:25:34.899772Z","steps":["trace[235922808] 'agreement among raft nodes before linearized reading' (duration: 1.114515362s)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:34.899852Z","caller":"traceutil/trace.go:171","msg":"trace[932755693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"601.456329ms","start":"2026-07-24T21:25:34.298378Z","end":"2026-07-24T21:25:34.899835Z","steps":["trace[932755693] 'agreement among raft nodes before linearized reading' (duration: 601.239244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:34.899836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.706644ms","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-07-24T21:25:34.900389Z","caller":"traceutil/trace.go:171","msg":"trace[716926046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"598.277227ms","start":"2026-07-24T21:25:34.302093Z","end":"2026-07-24T21:25:34.900371Z","steps":["trace[716926046] 'agreement among raft nodes before linearized reading' (duration: 597.490359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:34.90048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:34.302084Z","time spent":"598.381149ms","remote":"127.0.0.1:43990","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-07-24T21:25:34.899899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:34.298368Z","time spent":"601.5222ms","remote":"127.0.0.1:43990","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-07-24T21:25:34.899899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:33.78487Z","time spent":"1.115017024s","remote":"127.0.0.1:43722","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-07-24T21:25:34.900028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.362137ms","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-07-24T21:25:34.901215Z","caller":"traceutil/trace.go:171","msg":"trace[1590381790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"610.541523ms","start":"2026-07-24T21:25:34.290658Z","end":"2026-07-24T21:25:34.9012Z","steps":["trace[1590381790] 'agreement among raft nodes before linearized reading' (duration: 608.790634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:34.901359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:34.290537Z","time spent":"610.771479ms","remote":"127.0.0.1:43990","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-07-24T21:25:35.409419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369247907264800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T21:25:35.722885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.730901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:25:35.723173Z","caller":"traceutil/trace.go:171","msg":"trace[1046318937] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"815.184005ms","start":"2026-07-24T21:25:34.907944Z","end":"2026-07-24T21:25:35.723128Z","steps":["trace[1046318937] 'process raft request' (duration: 692.104697ms)","trace[1046318937] 'compare' (duration: 122.484595ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:35.72321Z","caller":"traceutil/trace.go:171","msg":"trace[132761333] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:629; }","duration":"814.516ms","start":"2026-07-24T21:25:34.908682Z","end":"2026-07-24T21:25:35.723198Z","steps":["trace[132761333] 'read index received' (duration: 691.361419ms)","trace[132761333] 'applied index is now lower than readState.Index' (duration: 123.153211ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:25:35.723304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:34.907892Z","time spent":"815.350789ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:25:35.723287Z","caller":"traceutil/trace.go:171","msg":"trace[2143780697] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"814.871458ms","start":"2026-07-24T21:25:34.908364Z","end":"2026-07-24T21:25:35.723235Z","steps":["trace[2143780697] 'process raft request' (duration: 814.718314ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:35.723347Z","caller":"traceutil/trace.go:171","msg":"trace[675744731] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"814.820437ms","start":"2026-07-24T21:25:34.908468Z","end":"2026-07-24T21:25:35.723289Z","steps":["trace[675744731] 'process raft request' (duration: 814.693204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:35.723502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:34.908331Z","time spent":"815.060042ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:25:35.723582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:34.908458Z","time spent":"814.996631ms","remote":"127.0.0.1:43990","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-07-24T21:25:35.723366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.653113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:35.72367Z","caller":"traceutil/trace.go:171","msg":"trace[1549148273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"815.003721ms","start":"2026-07-24T21:25:34.908654Z","end":"2026-07-24T21:25:35.723658Z","steps":["trace[1549148273] 'agreement among raft nodes before linearized reading' (duration: 814.627393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:35.723713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:34.908644Z","time spent":"815.059022ms","remote":"127.0.0.1:43722","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-07-24T21:25:35.724856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.469239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:35.725733Z","caller":"traceutil/trace.go:171","msg":"trace[579227071] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:606; }","duration":"815.326929ms","start":"2026-07-24T21:25:34.910363Z","end":"2026-07-24T21:25:35.72569Z","steps":["trace[579227071] 'agreement among raft nodes before linearized reading' (duration: 813.058547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:35.725795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:34.910355Z","time spent":"815.426601ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-24T21:25:36.956789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.902899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:25:36.956943Z","caller":"traceutil/trace.go:171","msg":"trace[430712719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"172.131595ms","start":"2026-07-24T21:25:36.784765Z","end":"2026-07-24T21:25:36.956896Z","steps":["trace[430712719] 'range keys from in-memory index tree' (duration: 171.794877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:40.885521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.102523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-07-24T21:25:40.885659Z","caller":"traceutil/trace.go:171","msg":"trace[283385505] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:682; }","duration":"167.317058ms","start":"2026-07-24T21:25:40.718314Z","end":"2026-07-24T21:25:40.885631Z","steps":["trace[283385505] 'range keys from in-memory index tree' (duration: 166.840787ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:41.477444Z","caller":"traceutil/trace.go:171","msg":"trace[1905054075] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"124.865856ms","start":"2026-07-24T21:25:41.352548Z","end":"2026-07-24T21:25:41.477414Z","steps":["trace[1905054075] 'process raft request' (duration: 124.21281ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:41.477686Z","caller":"traceutil/trace.go:171","msg":"trace[1066493902] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"127.954611ms","start":"2026-07-24T21:25:41.349683Z","end":"2026-07-24T21:25:41.477637Z","steps":["trace[1066493902] 'process raft request' (duration: 126.446764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:52.121141Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369247907265127,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T21:25:52.495092Z","caller":"traceutil/trace.go:171","msg":"trace[1529105225] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"960.729305ms","start":"2026-07-24T21:25:51.534332Z","end":"2026-07-24T21:25:52.495062Z","steps":["trace[1529105225] 'process raft request' (duration: 960.55181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:52.495459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:51.534311Z","time spent":"960.874388ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:25:52.60899Z","caller":"traceutil/trace.go:171","msg":"trace[28674025] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:797; }","duration":"988.591083ms","start":"2026-07-24T21:25:51.620363Z","end":"2026-07-24T21:25:52.608954Z","steps":["trace[28674025] 'read index received' (duration: 874.595375ms)","trace[28674025] 'applied index is now lower than readState.Index' (duration: 113.994138ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:25:52.609118Z","caller":"traceutil/trace.go:171","msg":"trace[1765761146] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"387.665662ms","start":"2026-07-24T21:25:52.221416Z","end":"2026-07-24T21:25:52.609081Z","steps":["trace[1765761146] 'process raft request' (duration: 387.450786ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:25:52.609268Z","caller":"traceutil/trace.go:171","msg":"trace[1803367363] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"950.197487ms","start":"2026-07-24T21:25:51.659027Z","end":"2026-07-24T21:25:52.609225Z","steps":["trace[1803367363] 'process raft request' (duration: 949.619001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:52.609532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.200561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-24T21:25:52.609541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:51.658987Z","time spent":"950.420183ms","remote":"127.0.0.1:43990","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-07-24T21:25:52.60958Z","caller":"traceutil/trace.go:171","msg":"trace[617963508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"752.266073ms","start":"2026-07-24T21:25:51.857301Z","end":"2026-07-24T21:25:52.609567Z","steps":["trace[617963508] 'agreement among raft nodes before linearized reading' (duration: 751.963985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:52.609617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:51.857291Z","time spent":"752.319754ms","remote":"127.0.0.1:43990","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-07-24T21:25:52.609643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.139003ms","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-07-24T21:25:52.609705Z","caller":"traceutil/trace.go:171","msg":"trace[1171915611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:769; }","duration":"757.270327ms","start":"2026-07-24T21:25:51.85242Z","end":"2026-07-24T21:25:52.60969Z","steps":["trace[1171915611] 'agreement among raft nodes before linearized reading' (duration: 756.823355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:52.609749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:51.852394Z","time spent":"757.346539ms","remote":"127.0.0.1:43990","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-07-24T21:25:52.609801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.877209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T21:25:52.609841Z","caller":"traceutil/trace.go:171","msg":"trace[164265537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"752.939081ms","start":"2026-07-24T21:25:51.856889Z","end":"2026-07-24T21:25:52.609828Z","steps":["trace[164265537] 'agreement among raft nodes before linearized reading' (duration: 752.456468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:52.609874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:51.856879Z","time spent":"752.983472ms","remote":"127.0.0.1:43990","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-07-24T21:25:52.609325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:52.221386Z","time spent":"387.812066ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:25:52.61025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.893047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-sb7b6\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-24T21:25:52.610325Z","caller":"traceutil/trace.go:171","msg":"trace[1828919785] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-sb7b6; range_end:; response_count:1; response_revision:769; }","duration":"990.017631ms","start":"2026-07-24T21:25:51.620295Z","end":"2026-07-24T21:25:52.610312Z","steps":["trace[1828919785] 'agreement among raft nodes before linearized reading' (duration: 988.889371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:25:52.610377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:25:51.620266Z","time spent":"990.102593ms","remote":"127.0.0.1:43926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-sb7b6\" "} {"level":"info","ts":"2026-07-24T21:26:03.021152Z","caller":"traceutil/trace.go:171","msg":"trace[623833019] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"115.150435ms","start":"2026-07-24T21:26:02.905971Z","end":"2026-07-24T21:26:03.021122Z","steps":["trace[623833019] 'process raft request' (duration: 115.091394ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:26:03.021443Z","caller":"traceutil/trace.go:171","msg":"trace[2098756972] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"129.664174ms","start":"2026-07-24T21:26:02.891729Z","end":"2026-07-24T21:26:03.021393Z","steps":["trace[2098756972] 'process raft request' (duration: 129.069477ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:26:03.056263Z","caller":"traceutil/trace.go:171","msg":"trace[743397208] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"119.220749ms","start":"2026-07-24T21:26:02.937003Z","end":"2026-07-24T21:26:03.056224Z","steps":["trace[743397208] 'process raft request' (duration: 119.007003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:26:03.235495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.894419ms","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-07-24T21:26:03.235609Z","caller":"traceutil/trace.go:171","msg":"trace[986003522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:890; }","duration":"111.078325ms","start":"2026-07-24T21:26:03.124503Z","end":"2026-07-24T21:26:03.235581Z","steps":["trace[986003522] 'range keys from in-memory index tree' (duration: 110.649082ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:27:51.803062Z","caller":"traceutil/trace.go:171","msg":"trace[133543091] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"120.218581ms","start":"2026-07-24T21:27:51.682801Z","end":"2026-07-24T21:27:51.803019Z","steps":["trace[133543091] 'process raft request' (duration: 99.873874ms)","trace[133543091] 'compare' (duration: 20.199591ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:27:59.383409Z","caller":"traceutil/trace.go:171","msg":"trace[342527090] linearizableReadLoop","detail":"{readStateIndex:1692; appliedIndex:1691; }","duration":"170.912149ms","start":"2026-07-24T21:27:59.21247Z","end":"2026-07-24T21:27:59.383382Z","steps":["trace[342527090] 'read index received' (duration: 170.68441ms)","trace[342527090] 'applied index is now lower than readState.Index' (duration: 226.799µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:27:59.383531Z","caller":"traceutil/trace.go:171","msg":"trace[1615768620] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"226.619309ms","start":"2026-07-24T21:27:59.156876Z","end":"2026-07-24T21:27:59.383495Z","steps":["trace[1615768620] 'process raft request' (duration: 226.342119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:27:59.383702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.21616ms","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-07-24T21:27:59.383755Z","caller":"traceutil/trace.go:171","msg":"trace[466861440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1632; }","duration":"171.289403ms","start":"2026-07-24T21:27:59.212453Z","end":"2026-07-24T21:27:59.383743Z","steps":["trace[466861440] 'agreement among raft nodes before linearized reading' (duration: 171.039393ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:03.470263Z","caller":"traceutil/trace.go:171","msg":"trace[1685346737] transaction","detail":"{read_only:false; response_revision:1681; number_of_response:1; }","duration":"253.703928ms","start":"2026-07-24T21:28:03.216482Z","end":"2026-07-24T21:28:03.470186Z","steps":["trace[1685346737] 'process raft request' (duration: 253.117985ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:11.881878Z","caller":"traceutil/trace.go:171","msg":"trace[2097247208] transaction","detail":"{read_only:false; response_revision:1747; number_of_response:1; }","duration":"192.408244ms","start":"2026-07-24T21:28:11.689429Z","end":"2026-07-24T21:28:11.881837Z","steps":["trace[2097247208] 'process raft request' (duration: 168.586651ms)","trace[2097247208] 'compare' (duration: 23.659167ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:28:11.901852Z","caller":"traceutil/trace.go:171","msg":"trace[811407769] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"212.049855ms","start":"2026-07-24T21:28:11.68976Z","end":"2026-07-24T21:28:11.90181Z","steps":["trace[811407769] 'process raft request' (duration: 211.843417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:12.754161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.293819ms","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-07-24T21:28:12.754294Z","caller":"traceutil/trace.go:171","msg":"trace[2118830138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1748; }","duration":"715.514827ms","start":"2026-07-24T21:28:12.038752Z","end":"2026-07-24T21:28:12.754267Z","steps":["trace[2118830138] 'range keys from in-memory index tree' (duration: 715.033189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:12.754353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:12.038724Z","time spent":"715.618102ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T21:28:12.754245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.259877ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:28:12.754572Z","caller":"traceutil/trace.go:171","msg":"trace[289473763] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1748; }","duration":"505.60453ms","start":"2026-07-24T21:28:12.248937Z","end":"2026-07-24T21:28:12.754541Z","steps":["trace[289473763] 'range keys from in-memory index tree' (duration: 505.236886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:36.787054Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369247907268243,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T21:28:37.28747Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369247907268243,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T21:28:37.386708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357055361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3ba891c1-c362-4d35-8eec-4ab23ddccfbc\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-24T21:28:37.386844Z","caller":"traceutil/trace.go:171","msg":"trace[250025135] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3ba891c1-c362-4d35-8eec-4ab23ddccfbc; range_end:; response_count:1; response_revision:2269; }","duration":"1.35726649s","start":"2026-07-24T21:28:36.029549Z","end":"2026-07-24T21:28:37.386816Z","steps":["trace[250025135] 'range keys from in-memory index tree' (duration: 1.35676135s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.386896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:36.029518Z","time spent":"1.357361483s","remote":"127.0.0.1:43926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3ba891c1-c362-4d35-8eec-4ab23ddccfbc\" "} {"level":"warn","ts":"2026-07-24T21:28:37.387129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321950944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T21:28:37.387264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.312475422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-24T21:28:37.387249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307704235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-24T21:28:37.387319Z","caller":"traceutil/trace.go:171","msg":"trace[1392754473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2269; }","duration":"1.312580896s","start":"2026-07-24T21:28:36.074723Z","end":"2026-07-24T21:28:37.387303Z","steps":["trace[1392754473] 'range keys from in-memory index tree' (duration: 1.312269604s)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:37.387341Z","caller":"traceutil/trace.go:171","msg":"trace[386349750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2269; }","duration":"1.322149352s","start":"2026-07-24T21:28:36.065076Z","end":"2026-07-24T21:28:37.387226Z","steps":["trace[386349750] 'range keys from in-memory index tree' (duration: 1.321757457s)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:37.38735Z","caller":"traceutil/trace.go:171","msg":"trace[1182054107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2269; }","duration":"1.30782034s","start":"2026-07-24T21:28:36.079504Z","end":"2026-07-24T21:28:37.387325Z","steps":["trace[1182054107] 'range keys from in-memory index tree' (duration: 1.307482626s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.387366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:36.074695Z","time spent":"1.312661299s","remote":"127.0.0.1:43990","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-07-24T21:28:37.387403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:36.079432Z","time spent":"1.307959904s","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T21:28:37.3875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:36.065025Z","time spent":"1.322456184s","remote":"127.0.0.1:43722","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-07-24T21:28:37.688761Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.389808322s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T21:28:37.689653Z","caller":"traceutil/trace.go:171","msg":"trace[472510899] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"1.559747019s","start":"2026-07-24T21:28:36.12985Z","end":"2026-07-24T21:28:37.689597Z","steps":["trace[472510899] 'process raft request' (duration: 1.559353683s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.690124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:36.129827Z","time spent":"1.560167085s","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:28:37.928147Z","caller":"traceutil/trace.go:171","msg":"trace[1731064958] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"534.32699ms","start":"2026-07-24T21:28:37.393791Z","end":"2026-07-24T21:28:37.928118Z","steps":["trace[1731064958] 'process raft request' (duration: 534.208445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.928391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:37.393757Z","time spent":"534.492116ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:28:37.928571Z","caller":"traceutil/trace.go:171","msg":"trace[450678351] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"783.344112ms","start":"2026-07-24T21:28:37.145215Z","end":"2026-07-24T21:28:37.928559Z","steps":["trace[450678351] 'process raft request' (duration: 782.51523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.928773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:37.145178Z","time spent":"783.423885ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:28:37.929018Z","caller":"traceutil/trace.go:171","msg":"trace[1766483195] linearizableReadLoop","detail":"{readStateIndex:2369; appliedIndex:2367; }","duration":"1.64228603s","start":"2026-07-24T21:28:36.286703Z","end":"2026-07-24T21:28:37.928989Z","steps":["trace[1766483195] 'read index received' (duration: 1.402398976s)","trace[1766483195] 'applied index is now lower than readState.Index' (duration: 239.885754ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:28:37.929082Z","caller":"traceutil/trace.go:171","msg":"trace[788729893] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"534.494336ms","start":"2026-07-24T21:28:37.394559Z","end":"2026-07-24T21:28:37.929053Z","steps":["trace[788729893] 'process raft request' (duration: 533.511367ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:28:37.929184Z","caller":"traceutil/trace.go:171","msg":"trace[530879942] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"408.97548ms","start":"2026-07-24T21:28:37.520191Z","end":"2026-07-24T21:28:37.929166Z","steps":["trace[530879942] 'process raft request' (duration: 408.636077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.929249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:37.394523Z","time spent":"534.636122ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:28:37.92934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:37.520158Z","time spent":"409.069834ms","remote":"127.0.0.1:43990","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-07-24T21:28:37.929473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.64277643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c0051a2c-c1d1-4086-898d-2e60eca7c429\" ","response":"range_response_count:1 size:3617"} {"level":"info","ts":"2026-07-24T21:28:37.92953Z","caller":"traceutil/trace.go:171","msg":"trace[59126797] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c0051a2c-c1d1-4086-898d-2e60eca7c429; range_end:; response_count:1; response_revision:2274; }","duration":"1.642884934s","start":"2026-07-24T21:28:36.28663Z","end":"2026-07-24T21:28:37.929515Z","steps":["trace[59126797] 'agreement among raft nodes before linearized reading' (duration: 1.642715157s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.929572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:36.286604Z","time spent":"1.642960157s","remote":"127.0.0.1:43926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3640,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c0051a2c-c1d1-4086-898d-2e60eca7c429\" "} {"level":"warn","ts":"2026-07-24T21:28:37.930313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543846048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3ba891c1-c362-4d35-8eec-4ab23ddccfbc\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-24T21:28:37.930366Z","caller":"traceutil/trace.go:171","msg":"trace[89095751] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3ba891c1-c362-4d35-8eec-4ab23ddccfbc; range_end:; response_count:1; response_revision:2276; }","duration":"1.54390344s","start":"2026-07-24T21:28:36.386449Z","end":"2026-07-24T21:28:37.930353Z","steps":["trace[89095751] 'agreement among raft nodes before linearized reading' (duration: 1.543672311s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.9304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:36.386309Z","time spent":"1.544083587s","remote":"127.0.0.1:43926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3ba891c1-c362-4d35-8eec-4ab23ddccfbc\" "} {"level":"info","ts":"2026-07-24T21:28:37.930712Z","caller":"traceutil/trace.go:171","msg":"trace[1847699188] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"181.17273ms","start":"2026-07-24T21:28:37.749524Z","end":"2026-07-24T21:28:37.930697Z","steps":["trace[1847699188] 'process raft request' (duration: 180.453032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.931185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.614796ms","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-07-24T21:28:37.931235Z","caller":"traceutil/trace.go:171","msg":"trace[1620943346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2276; }","duration":"209.71924ms","start":"2026-07-24T21:28:37.721505Z","end":"2026-07-24T21:28:37.931224Z","steps":["trace[1620943346] 'agreement among raft nodes before linearized reading' (duration: 209.595195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.931435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.76201ms","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-07-24T21:28:37.931468Z","caller":"traceutil/trace.go:171","msg":"trace[1397744792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2276; }","duration":"212.926766ms","start":"2026-07-24T21:28:37.718532Z","end":"2026-07-24T21:28:37.931459Z","steps":["trace[1397744792] 'agreement among raft nodes before linearized reading' (duration: 212.830862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.931629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.321733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:28:37.931666Z","caller":"traceutil/trace.go:171","msg":"trace[2080851542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2276; }","duration":"622.408736ms","start":"2026-07-24T21:28:37.309244Z","end":"2026-07-24T21:28:37.931653Z","steps":["trace[2080851542] 'agreement among raft nodes before linearized reading' (duration: 622.357824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.931691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:37.309215Z","time spent":"622.470839ms","remote":"127.0.0.1:43722","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-07-24T21:28:37.931943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.248109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:28:37.931981Z","caller":"traceutil/trace.go:171","msg":"trace[1725581496] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2276; }","duration":"835.342492ms","start":"2026-07-24T21:28:37.096628Z","end":"2026-07-24T21:28:37.931971Z","steps":["trace[1725581496] 'agreement among raft nodes before linearized reading' (duration: 835.207887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.932013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:37.096599Z","time spent":"835.404576ms","remote":"127.0.0.1:55744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-24T21:28:37.932261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016342174s","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-07-24T21:28:37.932301Z","caller":"traceutil/trace.go:171","msg":"trace[126198036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2276; }","duration":"1.016447389s","start":"2026-07-24T21:28:36.915843Z","end":"2026-07-24T21:28:37.932291Z","steps":["trace[126198036] 'agreement among raft nodes before linearized reading' (duration: 1.016329204s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.932334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:36.915813Z","time spent":"1.016508381s","remote":"127.0.0.1:43990","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-07-24T21:28:37.932589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148242769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:28:37.932631Z","caller":"traceutil/trace.go:171","msg":"trace[512397649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2276; }","duration":"1.148349943s","start":"2026-07-24T21:28:36.784268Z","end":"2026-07-24T21:28:37.932618Z","steps":["trace[512397649] 'agreement among raft nodes before linearized reading' (duration: 1.14828252s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.932663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:36.784236Z","time spent":"1.148420306s","remote":"127.0.0.1:43708","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-07-24T21:28:37.932808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40318514s","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":"info","ts":"2026-07-24T21:28:37.932905Z","caller":"traceutil/trace.go:171","msg":"trace[1994017489] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2276; }","duration":"1.403353087s","start":"2026-07-24T21:28:36.529527Z","end":"2026-07-24T21:28:37.93288Z","steps":["trace[1994017489] 'agreement among raft nodes before linearized reading' (duration: 1.403199651s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.932959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:36.529485Z","time spent":"1.403462431s","remote":"127.0.0.1:53228","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":"warn","ts":"2026-07-24T21:28:37.933164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511524451s","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-07-24T21:28:37.933231Z","caller":"traceutil/trace.go:171","msg":"trace[164351657] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2276; }","duration":"1.511614064s","start":"2026-07-24T21:28:36.421597Z","end":"2026-07-24T21:28:37.933211Z","steps":["trace[164351657] 'agreement among raft nodes before linearized reading' (duration: 1.511464798s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:37.93327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:28:36.421586Z","time spent":"1.511674636s","remote":"127.0.0.1:42958","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-07-24T21:28:46.087827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.275848ms","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-07-24T21:28:46.087966Z","caller":"traceutil/trace.go:171","msg":"trace[35686080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2371; }","duration":"101.524197ms","start":"2026-07-24T21:28:45.986405Z","end":"2026-07-24T21:28:46.087929Z","steps":["trace[35686080] 'agreement among raft nodes before linearized reading' (duration: 101.320409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:28:46.08821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.019017ms","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-07-24T21:28:46.088318Z","caller":"traceutil/trace.go:171","msg":"trace[34220942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2371; }","duration":"102.199013ms","start":"2026-07-24T21:28:45.986095Z","end":"2026-07-24T21:28:46.088294Z","steps":["trace[34220942] 'agreement among raft nodes before linearized reading' (duration: 101.024777ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:00.351993Z","caller":"traceutil/trace.go:171","msg":"trace[264656919] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"224.667085ms","start":"2026-07-24T21:29:00.127285Z","end":"2026-07-24T21:29:00.351952Z","steps":["trace[264656919] 'process raft request' (duration: 224.436276ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:00.352836Z","caller":"traceutil/trace.go:171","msg":"trace[597216348] transaction","detail":"{read_only:false; response_revision:2463; number_of_response:1; }","duration":"222.532371ms","start":"2026-07-24T21:29:00.130275Z","end":"2026-07-24T21:29:00.352807Z","steps":["trace[597216348] 'process raft request' (duration: 222.326233ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:00.353104Z","caller":"traceutil/trace.go:171","msg":"trace[1937054808] linearizableReadLoop","detail":"{readStateIndex:2571; appliedIndex:2568; }","duration":"184.669473ms","start":"2026-07-24T21:29:00.16841Z","end":"2026-07-24T21:29:00.35308Z","steps":["trace[1937054808] 'read index received' (duration: 183.563819ms)","trace[1937054808] 'applied index is now lower than readState.Index' (duration: 1.104824ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:29:00.353168Z","caller":"traceutil/trace.go:171","msg":"trace[817309258] transaction","detail":"{read_only:false; response_revision:2464; number_of_response:1; }","duration":"221.875145ms","start":"2026-07-24T21:29:00.131261Z","end":"2026-07-24T21:29:00.353136Z","steps":["trace[817309258] 'process raft request' (duration: 221.49954ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:00.35321Z","caller":"traceutil/trace.go:171","msg":"trace[1828054512] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"220.718179ms","start":"2026-07-24T21:29:00.132471Z","end":"2026-07-24T21:29:00.353189Z","steps":["trace[1828054512] 'process raft request' (duration: 220.507111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:00.353356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.932403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-24T21:29:00.35341Z","caller":"traceutil/trace.go:171","msg":"trace[1997329716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2465; }","duration":"185.030897ms","start":"2026-07-24T21:29:00.168364Z","end":"2026-07-24T21:29:00.353395Z","steps":["trace[1997329716] 'agreement among raft nodes before linearized reading' (duration: 184.8488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:00.353479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.679783ms","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-07-24T21:29:00.353508Z","caller":"traceutil/trace.go:171","msg":"trace[377596979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2465; }","duration":"161.744816ms","start":"2026-07-24T21:29:00.191755Z","end":"2026-07-24T21:29:00.3535Z","steps":["trace[377596979] 'agreement among raft nodes before linearized reading' (duration: 161.59994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:00.353498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.639687ms","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-07-24T21:29:00.353559Z","caller":"traceutil/trace.go:171","msg":"trace[1800328511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2465; }","duration":"150.776233ms","start":"2026-07-24T21:29:00.202764Z","end":"2026-07-24T21:29:00.35354Z","steps":["trace[1800328511] 'agreement among raft nodes before linearized reading' (duration: 150.597626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:00.353596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.058502ms","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-07-24T21:29:00.353632Z","caller":"traceutil/trace.go:171","msg":"trace[2062029785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2465; }","duration":"155.111204ms","start":"2026-07-24T21:29:00.198514Z","end":"2026-07-24T21:29:00.353626Z","steps":["trace[2062029785] 'agreement among raft nodes before linearized reading' (duration: 155.044481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:00.353723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.68454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-24T21:29:00.353739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.117352ms","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-07-24T21:29:00.353768Z","caller":"traceutil/trace.go:171","msg":"trace[1984251372] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2465; }","duration":"148.777343ms","start":"2026-07-24T21:29:00.204973Z","end":"2026-07-24T21:29:00.353751Z","steps":["trace[1984251372] 'agreement among raft nodes before linearized reading' (duration: 148.595026ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:00.353774Z","caller":"traceutil/trace.go:171","msg":"trace[2034596763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2465; }","duration":"159.215217ms","start":"2026-07-24T21:29:00.194549Z","end":"2026-07-24T21:29:00.353764Z","steps":["trace[2034596763] 'agreement among raft nodes before linearized reading' (duration: 159.116793ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:08.405007Z","caller":"traceutil/trace.go:171","msg":"trace[1656206542] transaction","detail":"{read_only:false; response_revision:2501; number_of_response:1; }","duration":"271.691055ms","start":"2026-07-24T21:29:08.133287Z","end":"2026-07-24T21:29:08.404978Z","steps":["trace[1656206542] 'process raft request' (duration: 271.485497ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:09.087931Z","caller":"traceutil/trace.go:171","msg":"trace[2094807245] transaction","detail":"{read_only:false; response_revision:2512; number_of_response:1; }","duration":"380.219738ms","start":"2026-07-24T21:29:08.707681Z","end":"2026-07-24T21:29:09.087901Z","steps":["trace[2094807245] 'process raft request' (duration: 380.122404ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:09.088Z","caller":"traceutil/trace.go:171","msg":"trace[797697326] transaction","detail":"{read_only:false; response_revision:2511; number_of_response:1; }","duration":"479.030414ms","start":"2026-07-24T21:29:08.60895Z","end":"2026-07-24T21:29:09.08798Z","steps":["trace[797697326] 'process raft request' (duration: 469.245106ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:09.088024Z","caller":"traceutil/trace.go:171","msg":"trace[1047649575] linearizableReadLoop","detail":"{readStateIndex:2619; appliedIndex:2617; }","duration":"477.208671ms","start":"2026-07-24T21:29:08.610778Z","end":"2026-07-24T21:29:09.087986Z","steps":["trace[1047649575] 'read index received' (duration: 95.450462ms)","trace[1047649575] 'applied index is now lower than readState.Index' (duration: 381.751129ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:09.08811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:08.707667Z","time spent":"380.353834ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:29:09.088106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:08.60893Z","time spent":"479.111526ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:29:09.08821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.333632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:29:09.088255Z","caller":"traceutil/trace.go:171","msg":"trace[1333587902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2512; }","duration":"302.417235ms","start":"2026-07-24T21:29:08.785827Z","end":"2026-07-24T21:29:09.088244Z","steps":["trace[1333587902] 'agreement among raft nodes before linearized reading' (duration: 302.348543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:09.088289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:08.7858Z","time spent":"302.483278ms","remote":"127.0.0.1:43722","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-07-24T21:29:09.088325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.734374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11986"} {"level":"info","ts":"2026-07-24T21:29:09.088377Z","caller":"traceutil/trace.go:171","msg":"trace[1373962996] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2512; }","duration":"124.837198ms","start":"2026-07-24T21:29:08.963527Z","end":"2026-07-24T21:29:09.088364Z","steps":["trace[1373962996] 'agreement among raft nodes before linearized reading' (duration: 124.671872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:09.088458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.573516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:29:09.088602Z","caller":"traceutil/trace.go:171","msg":"trace[954257270] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2512; }","duration":"477.863148ms","start":"2026-07-24T21:29:08.610718Z","end":"2026-07-24T21:29:09.088581Z","steps":["trace[954257270] 'agreement among raft nodes before linearized reading' (duration: 477.41328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:09.088697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:08.610696Z","time spent":"477.983853ms","remote":"127.0.0.1:55548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-07-24T21:29:11.670148Z","caller":"traceutil/trace.go:171","msg":"trace[1139015971] transaction","detail":"{read_only:false; response_revision:2531; number_of_response:1; }","duration":"205.133164ms","start":"2026-07-24T21:29:11.46498Z","end":"2026-07-24T21:29:11.670113Z","steps":["trace[1139015971] 'process raft request' (duration: 204.909175ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:13.383744Z","caller":"traceutil/trace.go:171","msg":"trace[1617153799] transaction","detail":"{read_only:false; response_revision:2542; number_of_response:1; }","duration":"172.958632ms","start":"2026-07-24T21:29:13.210748Z","end":"2026-07-24T21:29:13.383707Z","steps":["trace[1617153799] 'process raft request' (duration: 172.755334ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:14.541435Z","caller":"traceutil/trace.go:171","msg":"trace[2146055524] linearizableReadLoop","detail":"{readStateIndex:2656; appliedIndex:2653; }","duration":"101.520788ms","start":"2026-07-24T21:29:14.439885Z","end":"2026-07-24T21:29:14.541406Z","steps":["trace[2146055524] 'read index received' (duration: 79.158271ms)","trace[2146055524] 'applied index is now lower than readState.Index' (duration: 22.361207ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:29:14.541581Z","caller":"traceutil/trace.go:171","msg":"trace[730912430] transaction","detail":"{read_only:false; response_revision:2547; number_of_response:1; }","duration":"102.838771ms","start":"2026-07-24T21:29:14.438728Z","end":"2026-07-24T21:29:14.541567Z","steps":["trace[730912430] 'process raft request' (duration: 102.624492ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:14.541606Z","caller":"traceutil/trace.go:171","msg":"trace[1665309401] transaction","detail":"{read_only:false; response_revision:2546; number_of_response:1; }","duration":"103.374432ms","start":"2026-07-24T21:29:14.438187Z","end":"2026-07-24T21:29:14.541562Z","steps":["trace[1665309401] 'process raft request' (duration: 102.991976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:14.542355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.439575ms","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-07-24T21:29:14.542406Z","caller":"traceutil/trace.go:171","msg":"trace[442763879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2547; }","duration":"102.5455ms","start":"2026-07-24T21:29:14.439845Z","end":"2026-07-24T21:29:14.542391Z","steps":["trace[442763879] 'agreement among raft nodes before linearized reading' (duration: 102.412134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:14.905974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.96149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:29:14.906204Z","caller":"traceutil/trace.go:171","msg":"trace[329157655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2552; }","duration":"120.240971ms","start":"2026-07-24T21:29:14.785906Z","end":"2026-07-24T21:29:14.906147Z","steps":["trace[329157655] 'range keys from in-memory index tree' (duration: 119.860406ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:16.137194Z","caller":"traceutil/trace.go:171","msg":"trace[1710928490] linearizableReadLoop","detail":"{readStateIndex:2662; appliedIndex:2661; }","duration":"349.751354ms","start":"2026-07-24T21:29:15.787413Z","end":"2026-07-24T21:29:16.137165Z","steps":["trace[1710928490] 'read index received' (duration: 348.799666ms)","trace[1710928490] 'applied index is now lower than readState.Index' (duration: 950.188µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:16.137335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.89606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:29:16.13737Z","caller":"traceutil/trace.go:171","msg":"trace[816857099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2553; }","duration":"350.005054ms","start":"2026-07-24T21:29:15.787353Z","end":"2026-07-24T21:29:16.137358Z","steps":["trace[816857099] 'agreement among raft nodes before linearized reading' (duration: 349.920861ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:16.137321Z","caller":"traceutil/trace.go:171","msg":"trace[1325142940] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"482.584596ms","start":"2026-07-24T21:29:15.654683Z","end":"2026-07-24T21:29:16.137267Z","steps":["trace[1325142940] 'process raft request' (duration: 482.295774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:16.137408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:15.787323Z","time spent":"350.076777ms","remote":"127.0.0.1:43722","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-07-24T21:29:16.137649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:15.654645Z","time spent":"482.819236ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:29:16.397246Z","caller":"traceutil/trace.go:171","msg":"trace[1982719899] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"101.656506ms","start":"2026-07-24T21:29:16.295549Z","end":"2026-07-24T21:29:16.397205Z","steps":["trace[1982719899] 'process raft request' (duration: 43.266828ms)","trace[1982719899] 'compare' (duration: 58.071955ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:17.573607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.641513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:29:17.574007Z","caller":"traceutil/trace.go:171","msg":"trace[1467910448] transaction","detail":"{read_only:false; response_revision:2558; number_of_response:1; }","duration":"1.022165765s","start":"2026-07-24T21:29:16.551821Z","end":"2026-07-24T21:29:17.573987Z","steps":["trace[1467910448] 'process raft request' (duration: 1.022102292s)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:17.574082Z","caller":"traceutil/trace.go:171","msg":"trace[1205055121] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"1.048225079s","start":"2026-07-24T21:29:16.525793Z","end":"2026-07-24T21:29:17.574018Z","steps":["trace[1205055121] 'process raft request' (duration: 1.047922927s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:17.574121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.551776Z","time spent":"1.022275889s","remote":"127.0.0.1:43990","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-07-24T21:29:17.574147Z","caller":"traceutil/trace.go:171","msg":"trace[1243368871] linearizableReadLoop","detail":"{readStateIndex:2664; appliedIndex:2663; }","duration":"1.049920736s","start":"2026-07-24T21:29:16.524211Z","end":"2026-07-24T21:29:17.574132Z","steps":["trace[1243368871] 'read index received' (duration: 258.71484ms)","trace[1243368871] 'applied index is now lower than readState.Index' (duration: 791.204786ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:29:17.57413Z","caller":"traceutil/trace.go:171","msg":"trace[382733152] transaction","detail":"{read_only:false; response_revision:2557; number_of_response:1; }","duration":"1.023334951s","start":"2026-07-24T21:29:16.550749Z","end":"2026-07-24T21:29:17.574084Z","steps":["trace[382733152] 'process raft request' (duration: 1.023134193s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:17.574298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.525775Z","time spent":"1.048384285s","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:29:17.574355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.550732Z","time spent":"1.023511538s","remote":"127.0.0.1:43990","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-07-24T21:29:17.574315Z","caller":"traceutil/trace.go:171","msg":"trace[1902192087] transaction","detail":"{read_only:false; response_revision:2559; number_of_response:1; }","duration":"980.611295ms","start":"2026-07-24T21:29:16.593692Z","end":"2026-07-24T21:29:17.574303Z","steps":["trace[1902192087] 'process raft request' (duration: 980.260141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:17.574468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050226069s","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-07-24T21:29:17.574486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.593659Z","time spent":"980.806643ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:29:17.574516Z","caller":"traceutil/trace.go:171","msg":"trace[1833299120] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2559; }","duration":"1.050306032s","start":"2026-07-24T21:29:16.5242Z","end":"2026-07-24T21:29:17.574506Z","steps":["trace[1833299120] 'agreement among raft nodes before linearized reading' (duration: 1.050136965s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:17.574686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.524139Z","time spent":"1.050502049s","remote":"127.0.0.1:44192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":12,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-07-24T21:29:17.574789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.873533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:29:17.574827Z","caller":"traceutil/trace.go:171","msg":"trace[903046640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2559; }","duration":"954.926725ms","start":"2026-07-24T21:29:16.619893Z","end":"2026-07-24T21:29:17.57482Z","steps":["trace[903046640] 'agreement among raft nodes before linearized reading' (duration: 954.838982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:17.574871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.619878Z","time spent":"954.980588ms","remote":"127.0.0.1:43990","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-07-24T21:29:17.574896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.926895ms","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-07-24T21:29:17.574978Z","caller":"traceutil/trace.go:171","msg":"trace[1546054894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2559; }","duration":"955.0431ms","start":"2026-07-24T21:29:16.61992Z","end":"2026-07-24T21:29:17.574963Z","steps":["trace[1546054894] 'agreement among raft nodes before linearized reading' (duration: 954.810171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:17.575022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.619911Z","time spent":"955.102652ms","remote":"127.0.0.1:43990","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-07-24T21:29:17.575284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.343092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:29:17.575349Z","caller":"traceutil/trace.go:171","msg":"trace[1482908012] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2559; }","duration":"854.469707ms","start":"2026-07-24T21:29:16.720869Z","end":"2026-07-24T21:29:17.575339Z","steps":["trace[1482908012] 'agreement among raft nodes before linearized reading' (duration: 854.370713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:17.575386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.720837Z","time spent":"854.541919ms","remote":"127.0.0.1:60104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":4,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-07-24T21:29:17.574697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016745599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-24T21:29:17.575489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009819154s","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-07-24T21:29:17.575539Z","caller":"traceutil/trace.go:171","msg":"trace[1498440361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2559; }","duration":"1.009915158s","start":"2026-07-24T21:29:16.565613Z","end":"2026-07-24T21:29:17.575528Z","steps":["trace[1498440361] 'agreement among raft nodes before linearized reading' (duration: 1.009794663s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:17.57558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.565593Z","time spent":"1.009979651s","remote":"127.0.0.1:43990","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-07-24T21:29:17.575297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.699485ms","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-07-24T21:29:17.575673Z","caller":"traceutil/trace.go:171","msg":"trace[2083091180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2559; }","duration":"956.103082ms","start":"2026-07-24T21:29:16.61956Z","end":"2026-07-24T21:29:17.575663Z","steps":["trace[2083091180] 'agreement among raft nodes before linearized reading' (duration: 955.657204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:17.575712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.619546Z","time spent":"956.156024ms","remote":"127.0.0.1:43990","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-07-24T21:29:17.57565Z","caller":"traceutil/trace.go:171","msg":"trace[1901464114] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2559; }","duration":"1.017606843s","start":"2026-07-24T21:29:16.557919Z","end":"2026-07-24T21:29:17.575526Z","steps":["trace[1901464114] 'agreement among raft nodes before linearized reading' (duration: 1.016625584s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:17.575839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.557903Z","time spent":"1.017893695s","remote":"127.0.0.1:42958","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-07-24T21:29:17.575857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.352067ms","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-07-24T21:29:17.575908Z","caller":"traceutil/trace.go:171","msg":"trace[1744116043] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2559; }","duration":"538.461861ms","start":"2026-07-24T21:29:17.037439Z","end":"2026-07-24T21:29:17.575901Z","steps":["trace[1744116043] 'agreement among raft nodes before linearized reading' (duration: 538.054105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:17.575945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:17.037409Z","time spent":"538.523914ms","remote":"127.0.0.1:43808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":313,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-24T21:29:17.573768Z","caller":"traceutil/trace.go:171","msg":"trace[963998233] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"1.129143661s","start":"2026-07-24T21:29:16.44457Z","end":"2026-07-24T21:29:17.573714Z","steps":["trace[963998233] 'process raft request' (duration: 338.336881ms)","trace[963998233] 'compare' (duration: 790.331521ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:29:17.575951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.608402ms","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-07-24T21:29:17.576159Z","caller":"traceutil/trace.go:171","msg":"trace[1365508902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2559; }","duration":"956.849552ms","start":"2026-07-24T21:29:16.619297Z","end":"2026-07-24T21:29:17.576146Z","steps":["trace[1365508902] 'agreement among raft nodes before linearized reading' (duration: 956.109602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:17.576185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.444546Z","time spent":"1.131526706s","remote":"127.0.0.1:43990","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-07-24T21:29:17.576254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:16.61928Z","time spent":"956.937575ms","remote":"127.0.0.1:43990","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-07-24T21:29:18.817878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.811263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:29:18.819131Z","caller":"traceutil/trace.go:171","msg":"trace[1501433370] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"1.236723859s","start":"2026-07-24T21:29:17.582371Z","end":"2026-07-24T21:29:18.819094Z","steps":["trace[1501433370] 'process raft request' (duration: 237.352157ms)","trace[1501433370] 'compare' (duration: 997.401303ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:29:18.819477Z","caller":"traceutil/trace.go:171","msg":"trace[1080383110] transaction","detail":"{read_only:false; response_revision:2561; number_of_response:1; }","duration":"1.235815931s","start":"2026-07-24T21:29:17.583596Z","end":"2026-07-24T21:29:18.819412Z","steps":["trace[1080383110] 'process raft request' (duration: 1.234705221s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:18.819378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:17.582354Z","time spent":"1.23686029s","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:29:18.819539Z","caller":"traceutil/trace.go:171","msg":"trace[1211258771] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"1.235890021s","start":"2026-07-24T21:29:17.583596Z","end":"2026-07-24T21:29:18.819486Z","steps":["trace[1211258771] 'process raft request' (duration: 1.235699761s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:18.819735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:17.583569Z","time spent":"1.236044832s","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:29:18.819868Z","caller":"traceutil/trace.go:171","msg":"trace[1033114581] transaction","detail":"{read_only:false; response_revision:2564; number_of_response:1; }","duration":"1.235768872s","start":"2026-07-24T21:29:17.584069Z","end":"2026-07-24T21:29:18.819838Z","steps":["trace[1033114581] 'process raft request' (duration: 1.235323242s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:18.820002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033345858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:29:18.820076Z","caller":"traceutil/trace.go:171","msg":"trace[1979315492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2565; }","duration":"1.033541591s","start":"2026-07-24T21:29:17.786517Z","end":"2026-07-24T21:29:18.820059Z","steps":["trace[1979315492] 'agreement among raft nodes before linearized reading' (duration: 1.033365281s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:18.820086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:17.583569Z","time spent":"1.236079172s","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:29:18.82013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:17.786487Z","time spent":"1.033631882s","remote":"127.0.0.1:43722","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-07-24T21:29:18.820211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:17.584041Z","time spent":"1.235967093s","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:29:18.819736Z","caller":"traceutil/trace.go:171","msg":"trace[1104409660] linearizableReadLoop","detail":"{readStateIndex:2673; appliedIndex:2668; }","duration":"1.033084317s","start":"2026-07-24T21:29:17.786626Z","end":"2026-07-24T21:29:18.819711Z","steps":["trace[1104409660] 'read index received' (duration: 33.078815ms)","trace[1104409660] 'applied index is now lower than readState.Index' (duration: 1.000003922s)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:29:18.81949Z","caller":"traceutil/trace.go:171","msg":"trace[1369995200] transaction","detail":"{read_only:false; response_revision:2563; number_of_response:1; }","duration":"1.235388282s","start":"2026-07-24T21:29:17.584061Z","end":"2026-07-24T21:29:18.819449Z","steps":["trace[1369995200] 'process raft request' (duration: 1.235295182s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:18.820434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:17.584054Z","time spent":"1.236332572s","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:29:18.820565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.731246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c5570f54c30d22\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-24T21:29:18.820847Z","caller":"traceutil/trace.go:171","msg":"trace[855595858] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c5570f54c30d22; range_end:; response_count:1; response_revision:2565; }","duration":"610.072306ms","start":"2026-07-24T21:29:18.210752Z","end":"2026-07-24T21:29:18.820825Z","steps":["trace[855595858] 'agreement among raft nodes before linearized reading' (duration: 609.605676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:18.820909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:18.210723Z","time spent":"610.176956ms","remote":"127.0.0.1:43808","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.18c5570f54c30d22\" "} {"level":"warn","ts":"2026-07-24T21:29:18.821028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.89993ms","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-07-24T21:29:18.821084Z","caller":"traceutil/trace.go:171","msg":"trace[817400811] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2565; }","duration":"235.99776ms","start":"2026-07-24T21:29:18.585072Z","end":"2026-07-24T21:29:18.82107Z","steps":["trace[817400811] 'agreement among raft nodes before linearized reading' (duration: 235.47443ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:18.820961Z","caller":"traceutil/trace.go:171","msg":"trace[1229878871] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"137.400326ms","start":"2026-07-24T21:29:18.683538Z","end":"2026-07-24T21:29:18.820939Z","steps":["trace[1229878871] 'process raft request' (duration: 136.115936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:18.82123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.790352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T21:29:18.821387Z","caller":"traceutil/trace.go:171","msg":"trace[1415102332] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:2565; }","duration":"224.113742ms","start":"2026-07-24T21:29:18.597257Z","end":"2026-07-24T21:29:18.82137Z","steps":["trace[1415102332] 'agreement among raft nodes before linearized reading' (duration: 223.183392ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:40.130444Z","caller":"traceutil/trace.go:171","msg":"trace[801627942] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"440.607748ms","start":"2026-07-24T21:29:39.689806Z","end":"2026-07-24T21:29:40.130414Z","steps":["trace[801627942] 'process raft request' (duration: 440.413897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:40.130694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:39.689782Z","time spent":"440.758208ms","remote":"127.0.0.1:43990","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-07-24T21:29:40.143413Z","caller":"traceutil/trace.go:171","msg":"trace[1788463529] linearizableReadLoop","detail":"{readStateIndex:2809; appliedIndex:2805; }","duration":"357.41282ms","start":"2026-07-24T21:29:39.78599Z","end":"2026-07-24T21:29:40.143403Z","steps":["trace[1788463529] 'read index received' (duration: 344.294438ms)","trace[1788463529] 'applied index is now lower than readState.Index' (duration: 13.117612ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:29:40.143568Z","caller":"traceutil/trace.go:171","msg":"trace[1806692025] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"450.379232ms","start":"2026-07-24T21:29:39.693068Z","end":"2026-07-24T21:29:40.143447Z","steps":["trace[1806692025] 'process raft request' (duration: 450.06565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:40.143792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.527372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-24T21:29:40.14361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.641472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:29:40.143879Z","caller":"traceutil/trace.go:171","msg":"trace[1649287243] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2696; }","duration":"147.674453ms","start":"2026-07-24T21:29:39.99615Z","end":"2026-07-24T21:29:40.143825Z","steps":["trace[1649287243] 'agreement among raft nodes before linearized reading' (duration: 147.428611ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:29:40.143213Z","caller":"traceutil/trace.go:171","msg":"trace[1745781698] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"450.531973ms","start":"2026-07-24T21:29:39.692651Z","end":"2026-07-24T21:29:40.143183Z","steps":["trace[1745781698] 'process raft request' (duration: 450.412113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:40.143958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:39.69306Z","time spent":"450.680373ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:29:40.143884Z","caller":"traceutil/trace.go:171","msg":"trace[962413491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2696; }","duration":"357.965344ms","start":"2026-07-24T21:29:39.785895Z","end":"2026-07-24T21:29:40.14386Z","steps":["trace[962413491] 'agreement among raft nodes before linearized reading' (duration: 357.630492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:40.144147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:39.692643Z","time spent":"451.327808ms","remote":"127.0.0.1:43990","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-07-24T21:29:40.144221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:39.785846Z","time spent":"358.359476ms","remote":"127.0.0.1:43722","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-07-24T21:29:40.14322Z","caller":"traceutil/trace.go:171","msg":"trace[643237986] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"451.82596ms","start":"2026-07-24T21:29:39.69134Z","end":"2026-07-24T21:29:40.143166Z","steps":["trace[643237986] 'process raft request' (duration: 451.558639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:29:40.144797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:29:39.691335Z","time spent":"453.408899ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:29:45.600519Z","caller":"traceutil/trace.go:171","msg":"trace[744922957] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"175.189371ms","start":"2026-07-24T21:29:45.425302Z","end":"2026-07-24T21:29:45.600491Z","steps":["trace[744922957] 'process raft request' (duration: 175.02862ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:30:36.559161Z","caller":"traceutil/trace.go:171","msg":"trace[1150072255] transaction","detail":"{read_only:false; response_revision:3113; number_of_response:1; }","duration":"171.988068ms","start":"2026-07-24T21:30:36.387145Z","end":"2026-07-24T21:30:36.559133Z","steps":["trace[1150072255] 'process raft request' (duration: 165.551103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:30:47.849976Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369247907270336,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T21:30:47.947922Z","caller":"traceutil/trace.go:171","msg":"trace[593470796] transaction","detail":"{read_only:false; response_revision:3198; number_of_response:1; }","duration":"603.847444ms","start":"2026-07-24T21:30:47.344048Z","end":"2026-07-24T21:30:47.947895Z","steps":["trace[593470796] 'process raft request' (duration: 603.673041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:30:47.948179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:30:47.344031Z","time spent":"603.960286ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:30:47.975009Z","caller":"traceutil/trace.go:171","msg":"trace[1793739147] linearizableReadLoop","detail":"{readStateIndex:3328; appliedIndex:3326; }","duration":"625.642524ms","start":"2026-07-24T21:30:47.349332Z","end":"2026-07-24T21:30:47.974974Z","steps":["trace[1793739147] 'read index received' (duration: 598.489168ms)","trace[1793739147] 'applied index is now lower than readState.Index' (duration: 27.151846ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:30:47.975173Z","caller":"traceutil/trace.go:171","msg":"trace[587225146] transaction","detail":"{read_only:false; response_revision:3199; number_of_response:1; }","duration":"258.235346ms","start":"2026-07-24T21:30:47.716923Z","end":"2026-07-24T21:30:47.975159Z","steps":["trace[587225146] 'process raft request' (duration: 257.90255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:30:47.975423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.554327ms","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-07-24T21:30:47.975544Z","caller":"traceutil/trace.go:171","msg":"trace[1221338423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3199; }","duration":"618.732591ms","start":"2026-07-24T21:30:47.356788Z","end":"2026-07-24T21:30:47.975521Z","steps":["trace[1221338423] 'agreement among raft nodes before linearized reading' (duration: 618.487366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:30:47.975601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:30:47.356767Z","time spent":"618.823622ms","remote":"127.0.0.1:43990","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-07-24T21:30:47.975648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.288815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:30:47.975703Z","caller":"traceutil/trace.go:171","msg":"trace[1797342564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3199; }","duration":"626.395788ms","start":"2026-07-24T21:30:47.349294Z","end":"2026-07-24T21:30:47.975689Z","steps":["trace[1797342564] 'agreement among raft nodes before linearized reading' (duration: 626.189244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:30:47.975744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:30:47.349284Z","time spent":"626.450908ms","remote":"127.0.0.1:43990","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-07-24T21:30:47.97587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.530189ms","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-07-24T21:30:47.975894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.874439ms","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-07-24T21:30:47.975926Z","caller":"traceutil/trace.go:171","msg":"trace[1810756983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3199; }","duration":"615.90398ms","start":"2026-07-24T21:30:47.360013Z","end":"2026-07-24T21:30:47.975917Z","steps":["trace[1810756983] 'agreement among raft nodes before linearized reading' (duration: 615.816258ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:30:47.975928Z","caller":"traceutil/trace.go:171","msg":"trace[1492202257] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3199; }","duration":"146.637051ms","start":"2026-07-24T21:30:47.829274Z","end":"2026-07-24T21:30:47.975911Z","steps":["trace[1492202257] 'agreement among raft nodes before linearized reading' (duration: 146.500108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:30:47.975962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:30:47.359933Z","time spent":"616.021961ms","remote":"127.0.0.1:43990","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-07-24T21:30:47.976129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.033772ms","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-07-24T21:30:47.976261Z","caller":"traceutil/trace.go:171","msg":"trace[1742438147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3199; }","duration":"616.201265ms","start":"2026-07-24T21:30:47.360034Z","end":"2026-07-24T21:30:47.976235Z","steps":["trace[1742438147] 'agreement among raft nodes before linearized reading' (duration: 615.788878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:30:47.976321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:30:47.360029Z","time spent":"616.282007ms","remote":"127.0.0.1:43990","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-07-24T21:30:47.9763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.004616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:30:47.976494Z","caller":"traceutil/trace.go:171","msg":"trace[183748828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3199; }","duration":"190.213871ms","start":"2026-07-24T21:30:47.786204Z","end":"2026-07-24T21:30:47.976418Z","steps":["trace[183748828] 'agreement among raft nodes before linearized reading' (duration: 189.273324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:30:47.976502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.118741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-24T21:30:47.976821Z","caller":"traceutil/trace.go:171","msg":"trace[2016534711] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3199; }","duration":"396.64918ms","start":"2026-07-24T21:30:47.580146Z","end":"2026-07-24T21:30:47.976795Z","steps":["trace[2016534711] 'agreement among raft nodes before linearized reading' (duration: 395.711724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:30:47.976997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:30:47.580131Z","time spent":"396.783083ms","remote":"127.0.0.1:43926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-24T21:30:48.646727Z","caller":"traceutil/trace.go:171","msg":"trace[1425785150] transaction","detail":"{read_only:false; response_revision:3204; number_of_response:1; }","duration":"205.143195ms","start":"2026-07-24T21:30:48.441542Z","end":"2026-07-24T21:30:48.646685Z","steps":["trace[1425785150] 'process raft request' (duration: 204.87576ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:30:48.650633Z","caller":"traceutil/trace.go:171","msg":"trace[180846495] transaction","detail":"{read_only:false; response_revision:3205; number_of_response:1; }","duration":"206.105372ms","start":"2026-07-24T21:30:48.444491Z","end":"2026-07-24T21:30:48.650596Z","steps":["trace[180846495] 'process raft request' (duration: 205.845657ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:30:48.650666Z","caller":"traceutil/trace.go:171","msg":"trace[2092070481] transaction","detail":"{read_only:false; response_revision:3206; number_of_response:1; }","duration":"206.105872ms","start":"2026-07-24T21:30:48.444536Z","end":"2026-07-24T21:30:48.650642Z","steps":["trace[2092070481] 'process raft request' (duration: 205.950939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:30:48.95767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.825588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T21:30:48.957699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.386538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-07-24T21:30:48.957805Z","caller":"traceutil/trace.go:171","msg":"trace[46828880] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:3208; }","duration":"216.564491ms","start":"2026-07-24T21:30:48.741213Z","end":"2026-07-24T21:30:48.957777Z","steps":["trace[46828880] 'range keys from in-memory index tree' (duration: 216.069822ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:30:48.957807Z","caller":"traceutil/trace.go:171","msg":"trace[1684843019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3208; }","duration":"171.02251ms","start":"2026-07-24T21:30:48.786742Z","end":"2026-07-24T21:30:48.957765Z","steps":["trace[1684843019] 'range keys from in-memory index tree' (duration: 170.719995ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:34:45.610218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2726} {"level":"info","ts":"2026-07-24T21:34:45.690872Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2726,"took":"79.243483ms","hash":1925895436,"current-db-size-bytes":38469632,"current-db-size":"38 MB","current-db-size-in-use-bytes":13901824,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-24T21:34:45.690961Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1925895436,"revision":2726,"compact-revision":-1} {"level":"info","ts":"2026-07-24T21:36:41.582695Z","caller":"traceutil/trace.go:171","msg":"trace[1533093122] transaction","detail":"{read_only:false; response_revision:5609; number_of_response:1; }","duration":"117.459508ms","start":"2026-07-24T21:36:41.465207Z","end":"2026-07-24T21:36:41.582666Z","steps":["trace[1533093122] 'process raft request' (duration: 117.22734ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:19.10539Z","caller":"traceutil/trace.go:171","msg":"trace[80436924] linearizableReadLoop","detail":"{readStateIndex:7039; appliedIndex:7038; }","duration":"230.923364ms","start":"2026-07-24T21:38:18.874437Z","end":"2026-07-24T21:38:19.10536Z","steps":["trace[80436924] 'read index received' (duration: 230.882333ms)","trace[80436924] 'applied index is now lower than readState.Index' (duration: 39.971µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:38:19.105556Z","caller":"traceutil/trace.go:171","msg":"trace[11988262] transaction","detail":"{read_only:false; response_revision:6777; number_of_response:1; }","duration":"296.262214ms","start":"2026-07-24T21:38:18.809256Z","end":"2026-07-24T21:38:19.105518Z","steps":["trace[11988262] 'process raft request' (duration: 295.859777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:19.105771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.24563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-24T21:38:19.105855Z","caller":"traceutil/trace.go:171","msg":"trace[971427464] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:6777; }","duration":"231.437793ms","start":"2026-07-24T21:38:18.874399Z","end":"2026-07-24T21:38:19.105837Z","steps":["trace[971427464] 'agreement among raft nodes before linearized reading' (duration: 231.125557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:19.105901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.613983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-07-24T21:38:19.10599Z","caller":"traceutil/trace.go:171","msg":"trace[1124011194] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:6777; }","duration":"220.741206ms","start":"2026-07-24T21:38:18.885231Z","end":"2026-07-24T21:38:19.105972Z","steps":["trace[1124011194] 'agreement among raft nodes before linearized reading' (duration: 220.400879ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:20.361513Z","caller":"traceutil/trace.go:171","msg":"trace[1780282122] transaction","detail":"{read_only:false; response_revision:6792; number_of_response:1; }","duration":"229.706892ms","start":"2026-07-24T21:38:20.131785Z","end":"2026-07-24T21:38:20.361492Z","steps":["trace[1780282122] 'process raft request' (duration: 229.57456ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:20.362211Z","caller":"traceutil/trace.go:171","msg":"trace[16505089] transaction","detail":"{read_only:false; response_revision:6793; number_of_response:1; }","duration":"228.228954ms","start":"2026-07-24T21:38:20.13397Z","end":"2026-07-24T21:38:20.362199Z","steps":["trace[16505089] 'process raft request' (duration: 228.118002ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:20.36221Z","caller":"traceutil/trace.go:171","msg":"trace[1868615738] linearizableReadLoop","detail":"{readStateIndex:7055; appliedIndex:7053; }","duration":"169.237475ms","start":"2026-07-24T21:38:20.192953Z","end":"2026-07-24T21:38:20.36219Z","steps":["trace[1868615738] 'read index received' (duration: 168.448771ms)","trace[1868615738] 'applied index is now lower than readState.Index' (duration: 787.734µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:38:20.362505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.52635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ed368f76-b466-44a2-99bb-c0da9545a4bf\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-07-24T21:38:20.362599Z","caller":"traceutil/trace.go:171","msg":"trace[1982400315] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ed368f76-b466-44a2-99bb-c0da9545a4bf; range_end:; response_count:1; response_revision:6793; }","duration":"169.664843ms","start":"2026-07-24T21:38:20.192905Z","end":"2026-07-24T21:38:20.36257Z","steps":["trace[1982400315] 'agreement among raft nodes before linearized reading' (duration: 169.378508ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:21.929859Z","caller":"traceutil/trace.go:171","msg":"trace[1022672738] linearizableReadLoop","detail":"{readStateIndex:7070; appliedIndex:7069; }","duration":"175.055244ms","start":"2026-07-24T21:38:21.754782Z","end":"2026-07-24T21:38:21.929837Z","steps":["trace[1022672738] 'read index received' (duration: 174.897781ms)","trace[1022672738] 'applied index is now lower than readState.Index' (duration: 156.273µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:38:21.929901Z","caller":"traceutil/trace.go:171","msg":"trace[1896471486] transaction","detail":"{read_only:false; response_revision:6808; number_of_response:1; }","duration":"297.163048ms","start":"2026-07-24T21:38:21.632718Z","end":"2026-07-24T21:38:21.929881Z","steps":["trace[1896471486] 'process raft request' (duration: 296.968724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:21.930053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.761469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:38:21.93008Z","caller":"traceutil/trace.go:171","msg":"trace[751530195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6808; }","duration":"143.8265ms","start":"2026-07-24T21:38:21.786246Z","end":"2026-07-24T21:38:21.930073Z","steps":["trace[751530195] 'agreement among raft nodes before linearized reading' (duration: 143.763619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:21.930444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.666545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f23eaae6-5454-4d31-a7a2-561c31f5217a\" ","response":"range_response_count:1 size:4077"} {"level":"info","ts":"2026-07-24T21:38:21.930476Z","caller":"traceutil/trace.go:171","msg":"trace[858658283] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f23eaae6-5454-4d31-a7a2-561c31f5217a; range_end:; response_count:1; response_revision:6808; }","duration":"175.739817ms","start":"2026-07-24T21:38:21.754725Z","end":"2026-07-24T21:38:21.930465Z","steps":["trace[858658283] 'agreement among raft nodes before linearized reading' (duration: 175.216057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:22.309557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.274993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T21:38:22.309661Z","caller":"traceutil/trace.go:171","msg":"trace[1643760076] transaction","detail":"{read_only:false; response_revision:6809; number_of_response:1; }","duration":"373.832919ms","start":"2026-07-24T21:38:21.935813Z","end":"2026-07-24T21:38:22.309646Z","steps":["trace[1643760076] 'process raft request' (duration: 183.510699ms)","trace[1643760076] 'compare' (duration: 187.796713ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:38:22.309721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:21.935801Z","time spent":"373.88088ms","remote":"127.0.0.1:44002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:38:22.354774Z","caller":"traceutil/trace.go:171","msg":"trace[757513297] transaction","detail":"{read_only:false; response_revision:6810; number_of_response:1; }","duration":"418.65074ms","start":"2026-07-24T21:38:21.9361Z","end":"2026-07-24T21:38:22.354751Z","steps":["trace[757513297] 'process raft request' (duration: 418.373775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:22.354918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:21.936092Z","time spent":"418.750552ms","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1249,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:38:22.355071Z","caller":"traceutil/trace.go:171","msg":"trace[1960547632] linearizableReadLoop","detail":"{readStateIndex:7072; appliedIndex:7070; }","duration":"418.678631ms","start":"2026-07-24T21:38:21.936357Z","end":"2026-07-24T21:38:22.355035Z","steps":["trace[1960547632] 'read index received' (duration: 182.971169ms)","trace[1960547632] 'applied index is now lower than readState.Index' (duration: 235.705752ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T21:38:22.355327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.968966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10749"} {"level":"info","ts":"2026-07-24T21:38:22.355355Z","caller":"traceutil/trace.go:171","msg":"trace[1016077576] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:6811; }","duration":"419.031827ms","start":"2026-07-24T21:38:21.936316Z","end":"2026-07-24T21:38:22.355348Z","steps":["trace[1016077576] 'agreement among raft nodes before linearized reading' (duration: 418.879805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:22.355371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:21.936297Z","time spent":"419.071308ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":10772,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-24T21:38:22.355483Z","caller":"traceutil/trace.go:171","msg":"trace[311496175] transaction","detail":"{read_only:false; response_revision:6811; number_of_response:1; }","duration":"418.717102ms","start":"2026-07-24T21:38:21.936759Z","end":"2026-07-24T21:38:22.355476Z","steps":["trace[311496175] 'process raft request' (duration: 417.926797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:22.355512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:21.936749Z","time spent":"418.746832ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4883,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:38:22.355525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.876601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6jkfr.18c5578f42a49c4a\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-07-24T21:38:22.355565Z","caller":"traceutil/trace.go:171","msg":"trace[883534462] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6jkfr.18c5578f42a49c4a; range_end:; response_count:1; response_revision:6811; }","duration":"190.977432ms","start":"2026-07-24T21:38:22.164573Z","end":"2026-07-24T21:38:22.355551Z","steps":["trace[883534462] 'agreement among raft nodes before linearized reading' (duration: 190.785559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:22.355764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.296663ms","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-07-24T21:38:22.355786Z","caller":"traceutil/trace.go:171","msg":"trace[895367706] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:6811; }","duration":"163.328654ms","start":"2026-07-24T21:38:22.192452Z","end":"2026-07-24T21:38:22.35578Z","steps":["trace[895367706] 'agreement among raft nodes before linearized reading' (duration: 163.261962ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:29.521455Z","caller":"traceutil/trace.go:171","msg":"trace[354206522] linearizableReadLoop","detail":"{readStateIndex:7175; appliedIndex:7174; }","duration":"276.858595ms","start":"2026-07-24T21:38:29.244581Z","end":"2026-07-24T21:38:29.52144Z","steps":["trace[354206522] 'read index received' (duration: 276.709992ms)","trace[354206522] 'applied index is now lower than readState.Index' (duration: 147.973µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:38:29.52154Z","caller":"traceutil/trace.go:171","msg":"trace[409059114] transaction","detail":"{read_only:false; response_revision:6910; number_of_response:1; }","duration":"525.784175ms","start":"2026-07-24T21:38:28.995748Z","end":"2026-07-24T21:38:29.521532Z","steps":["trace[409059114] 'process raft request' (duration: 525.580691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:29.521623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:28.995719Z","time spent":"525.836505ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T21:38:29.52178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.170172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-696bf97b8c-thp44.18c55791252304f8\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-24T21:38:29.521908Z","caller":"traceutil/trace.go:171","msg":"trace[1201709404] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-696bf97b8c-thp44.18c55791252304f8; range_end:; response_count:1; response_revision:6910; }","duration":"277.347205ms","start":"2026-07-24T21:38:29.244538Z","end":"2026-07-24T21:38:29.521885Z","steps":["trace[1201709404] 'agreement among raft nodes before linearized reading' (duration: 277.026509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:29.55891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.347877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T21:38:29.559005Z","caller":"traceutil/trace.go:171","msg":"trace[305273000] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:6911; }","duration":"203.49106ms","start":"2026-07-24T21:38:29.355492Z","end":"2026-07-24T21:38:29.558983Z","steps":["trace[305273000] 'agreement among raft nodes before linearized reading' (duration: 203.247725ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:29.734781Z","caller":"traceutil/trace.go:171","msg":"trace[759227899] transaction","detail":"{read_only:false; response_revision:6912; number_of_response:1; }","duration":"210.163487ms","start":"2026-07-24T21:38:29.524592Z","end":"2026-07-24T21:38:29.734755Z","steps":["trace[759227899] 'process raft request' (duration: 204.61199ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:30.610823Z","caller":"traceutil/trace.go:171","msg":"trace[2129709366] transaction","detail":"{read_only:false; response_revision:6913; number_of_response:1; }","duration":"127.849747ms","start":"2026-07-24T21:38:30.482958Z","end":"2026-07-24T21:38:30.610808Z","steps":["trace[2129709366] 'process raft request' (duration: 127.727324ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:31.101952Z","caller":"traceutil/trace.go:171","msg":"trace[1033404337] transaction","detail":"{read_only:false; response_revision:6915; number_of_response:1; }","duration":"521.07114ms","start":"2026-07-24T21:38:30.580857Z","end":"2026-07-24T21:38:31.101929Z","steps":["trace[1033404337] 'process raft request' (duration: 521.025229ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:31.102002Z","caller":"traceutil/trace.go:171","msg":"trace[1614143695] transaction","detail":"{read_only:false; response_revision:6914; number_of_response:1; }","duration":"607.004641ms","start":"2026-07-24T21:38:30.494952Z","end":"2026-07-24T21:38:31.101956Z","steps":["trace[1614143695] 'process raft request' (duration: 606.812118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:31.102077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:30.580835Z","time spent":"521.183412ms","remote":"127.0.0.1:43990","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-07-24T21:38:31.102188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:30.494937Z","time spent":"607.158114ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T21:38:31.10225Z","caller":"traceutil/trace.go:171","msg":"trace[257207662] transaction","detail":"{read_only:false; response_revision:6916; number_of_response:1; }","duration":"512.011376ms","start":"2026-07-24T21:38:30.590215Z","end":"2026-07-24T21:38:31.102227Z","steps":["trace[257207662] 'process raft request' (duration: 511.69133ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:31.102355Z","caller":"traceutil/trace.go:171","msg":"trace[1906623081] linearizableReadLoop","detail":"{readStateIndex:7179; appliedIndex:7178; }","duration":"583.259355ms","start":"2026-07-24T21:38:30.519088Z","end":"2026-07-24T21:38:31.102348Z","steps":["trace[1906623081] 'read index received' (duration: 91.705562ms)","trace[1906623081] 'applied index is now lower than readState.Index' (duration: 491.553223ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:38:31.102417Z","caller":"traceutil/trace.go:171","msg":"trace[1712704922] transaction","detail":"{read_only:false; response_revision:6917; number_of_response:1; }","duration":"509.387835ms","start":"2026-07-24T21:38:30.593023Z","end":"2026-07-24T21:38:31.102411Z","steps":["trace[1712704922] 'process raft request' (duration: 509.11749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:31.102399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:30.590181Z","time spent":"512.145519ms","remote":"127.0.0.1:43990","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-07-24T21:38:31.102451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:30.593008Z","time spent":"509.426066ms","remote":"127.0.0.1:43990","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-07-24T21:38:31.102546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.465519ms","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-07-24T21:38:31.102566Z","caller":"traceutil/trace.go:171","msg":"trace[204359989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6917; }","duration":"583.52435ms","start":"2026-07-24T21:38:30.519037Z","end":"2026-07-24T21:38:31.102562Z","steps":["trace[204359989] 'agreement among raft nodes before linearized reading' (duration: 583.450749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:31.102596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:30.519026Z","time spent":"583.55064ms","remote":"127.0.0.1:43990","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-07-24T21:38:31.102667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.061817ms","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-07-24T21:38:31.102683Z","caller":"traceutil/trace.go:171","msg":"trace[1805343663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6917; }","duration":"498.116278ms","start":"2026-07-24T21:38:30.604564Z","end":"2026-07-24T21:38:31.10268Z","steps":["trace[1805343663] 'agreement among raft nodes before linearized reading' (duration: 498.072498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:31.102696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:30.604552Z","time spent":"498.141119ms","remote":"127.0.0.1:43990","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-07-24T21:38:31.102753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.031398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:38:31.102768Z","caller":"traceutil/trace.go:171","msg":"trace[1667931337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6917; }","duration":"459.091739ms","start":"2026-07-24T21:38:30.643673Z","end":"2026-07-24T21:38:31.102764Z","steps":["trace[1667931337] 'agreement among raft nodes before linearized reading' (duration: 459.052288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:31.10278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:30.643649Z","time spent":"459.128029ms","remote":"127.0.0.1:43990","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-07-24T21:38:31.102779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.680494ms","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-07-24T21:38:31.102816Z","caller":"traceutil/trace.go:171","msg":"trace[1920150462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6917; }","duration":"454.743825ms","start":"2026-07-24T21:38:30.648062Z","end":"2026-07-24T21:38:31.102806Z","steps":["trace[1920150462] 'agreement among raft nodes before linearized reading' (duration: 454.624822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:31.102843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.801038ms","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-07-24T21:38:31.102856Z","caller":"traceutil/trace.go:171","msg":"trace[1230264141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6917; }","duration":"464.847269ms","start":"2026-07-24T21:38:30.638004Z","end":"2026-07-24T21:38:31.102851Z","steps":["trace[1230264141] 'agreement among raft nodes before linearized reading' (duration: 464.807508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:31.102885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:30.637985Z","time spent":"464.89468ms","remote":"127.0.0.1:43990","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-07-24T21:38:31.102853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:30.648048Z","time spent":"454.799437ms","remote":"127.0.0.1:43990","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-07-24T21:38:31.210848Z","caller":"traceutil/trace.go:171","msg":"trace[1770620208] transaction","detail":"{read_only:false; response_revision:6919; number_of_response:1; }","duration":"101.372614ms","start":"2026-07-24T21:38:31.109449Z","end":"2026-07-24T21:38:31.210822Z","steps":["trace[1770620208] 'process raft request' (duration: 101.200021ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:31.211089Z","caller":"traceutil/trace.go:171","msg":"trace[1824736040] transaction","detail":"{read_only:false; response_revision:6920; number_of_response:1; }","duration":"100.999777ms","start":"2026-07-24T21:38:31.110069Z","end":"2026-07-24T21:38:31.211069Z","steps":["trace[1824736040] 'process raft request' (duration: 100.709841ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:31.211368Z","caller":"traceutil/trace.go:171","msg":"trace[1481594012] transaction","detail":"{read_only:false; response_revision:6921; number_of_response:1; }","duration":"101.275682ms","start":"2026-07-24T21:38:31.110085Z","end":"2026-07-24T21:38:31.211361Z","steps":["trace[1481594012] 'process raft request' (duration: 100.923486ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:31.211536Z","caller":"traceutil/trace.go:171","msg":"trace[890324875] transaction","detail":"{read_only:false; response_revision:6922; number_of_response:1; }","duration":"101.395404ms","start":"2026-07-24T21:38:31.110113Z","end":"2026-07-24T21:38:31.211509Z","steps":["trace[890324875] 'process raft request' (duration: 101.214151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:33.382204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.384328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T21:38:33.382279Z","caller":"traceutil/trace.go:171","msg":"trace[1718959431] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6929; }","duration":"703.4919ms","start":"2026-07-24T21:38:32.678771Z","end":"2026-07-24T21:38:33.382263Z","steps":["trace[1718959431] 'range keys from in-memory index tree' (duration: 703.330077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:33.38231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T21:38:32.678758Z","time spent":"703.544152ms","remote":"127.0.0.1:44192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-24T21:38:33.383478Z","caller":"traceutil/trace.go:171","msg":"trace[1573393851] transaction","detail":"{read_only:false; response_revision:6930; number_of_response:1; }","duration":"274.116284ms","start":"2026-07-24T21:38:33.109335Z","end":"2026-07-24T21:38:33.383451Z","steps":["trace[1573393851] 'process raft request' (duration: 273.783227ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:33.387432Z","caller":"traceutil/trace.go:171","msg":"trace[2056847090] linearizableReadLoop","detail":"{readStateIndex:7198; appliedIndex:7194; }","duration":"182.988088ms","start":"2026-07-24T21:38:33.204427Z","end":"2026-07-24T21:38:33.387415Z","steps":["trace[2056847090] 'read index received' (duration: 178.686024ms)","trace[2056847090] 'applied index is now lower than readState.Index' (duration: 4.301484ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T21:38:33.387734Z","caller":"traceutil/trace.go:171","msg":"trace[1148709607] transaction","detail":"{read_only:false; response_revision:6931; number_of_response:1; }","duration":"277.781916ms","start":"2026-07-24T21:38:33.109943Z","end":"2026-07-24T21:38:33.387724Z","steps":["trace[1148709607] 'process raft request' (duration: 277.303417ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:33.387784Z","caller":"traceutil/trace.go:171","msg":"trace[612214120] transaction","detail":"{read_only:false; response_revision:6932; number_of_response:1; }","duration":"277.723355ms","start":"2026-07-24T21:38:33.110055Z","end":"2026-07-24T21:38:33.387778Z","steps":["trace[612214120] 'process raft request' (duration: 277.290656ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:33.387823Z","caller":"traceutil/trace.go:171","msg":"trace[1949357893] transaction","detail":"{read_only:false; response_revision:6933; number_of_response:1; }","duration":"277.023341ms","start":"2026-07-24T21:38:33.110795Z","end":"2026-07-24T21:38:33.387819Z","steps":["trace[1949357893] 'process raft request' (duration: 276.578402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:33.387948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.376233ms","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-07-24T21:38:33.387969Z","caller":"traceutil/trace.go:171","msg":"trace[1088986023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6934; }","duration":"171.430943ms","start":"2026-07-24T21:38:33.216533Z","end":"2026-07-24T21:38:33.387964Z","steps":["trace[1088986023] 'agreement among raft nodes before linearized reading' (duration: 171.344532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:33.388042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.62672ms","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-07-24T21:38:33.388057Z","caller":"traceutil/trace.go:171","msg":"trace[1458290438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6934; }","duration":"183.679841ms","start":"2026-07-24T21:38:33.204374Z","end":"2026-07-24T21:38:33.388054Z","steps":["trace[1458290438] 'agreement among raft nodes before linearized reading' (duration: 183.62864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:33.388048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.954653ms","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-07-24T21:38:33.388148Z","caller":"traceutil/trace.go:171","msg":"trace[198230346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6934; }","duration":"174.068675ms","start":"2026-07-24T21:38:33.214065Z","end":"2026-07-24T21:38:33.388133Z","steps":["trace[198230346] 'agreement among raft nodes before linearized reading' (duration: 173.80908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:33.388704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.050556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T21:38:33.388727Z","caller":"traceutil/trace.go:171","msg":"trace[413921195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6934; }","duration":"173.096006ms","start":"2026-07-24T21:38:33.215626Z","end":"2026-07-24T21:38:33.388722Z","steps":["trace[413921195] 'agreement among raft nodes before linearized reading' (duration: 172.986814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T21:38:33.388819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.397042ms","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-07-24T21:38:33.388832Z","caller":"traceutil/trace.go:171","msg":"trace[876070258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6934; }","duration":"173.432472ms","start":"2026-07-24T21:38:33.215395Z","end":"2026-07-24T21:38:33.388828Z","steps":["trace[876070258] 'agreement among raft nodes before linearized reading' (duration: 173.375741ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:38:33.860986Z","caller":"traceutil/trace.go:171","msg":"trace[181710479] transaction","detail":"{read_only:false; response_revision:6953; number_of_response:1; }","duration":"132.418826ms","start":"2026-07-24T21:38:33.728536Z","end":"2026-07-24T21:38:33.860954Z","steps":["trace[181710479] 'process raft request' (duration: 132.199802ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T21:39:45.617634Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4756} {"level":"info","ts":"2026-07-24T21:39:45.678844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4756,"took":"59.509042ms","hash":2609120131,"current-db-size-bytes":38469632,"current-db-size":"38 MB","current-db-size-in-use-bytes":30695424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-24T21:39:45.678926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2609120131,"revision":4756,"compact-revision":2726}