{"level":"warn","ts":"2026-04-22T00:53:28.184059Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T00:53:28.184162Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-04-22T00:53:28.184233Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T00:53:28.184246Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-04-22T00:53:28.184261Z","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-04-22T00:53:28.184899Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-04-22T00:53:28.184972Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-04-22T00:53:28.190587Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.407842ms"} {"level":"info","ts":"2026-04-22T00:53:28.204215Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-04-22T00:53:28.204316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T00:53:28.204338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-04-22T00:53:28.204356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T00:53:28.204375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-04-22T00:53:28.204426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-04-22T00:53:28.212408Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T00:53:28.213929Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T00:53:28.214827Z","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-04-22T00:53:28.217061Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T00:53:28.21735Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T00:53:28.217728Z","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-04-22T00:53:28.217797Z","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-04-22T00:53:28.217809Z","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-04-22T00:53:28.219304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-04-22T00:53:28.219403Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-04-22T00:53:28.223999Z","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-04-22T00:53:28.224095Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-04-22T00:53:28.224148Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-04-22T00:53:28.224247Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T00:53:28.224279Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T00:53:29.00493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T00:53:29.004986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T00:53:29.00502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-04-22T00:53:29.00504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-04-22T00:53:29.005055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-04-22T00:53:29.005071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-04-22T00:53:29.005094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-04-22T00:53:29.006111Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:53:29.006938Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T00:53:29.006949Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T00:53:29.006989Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T00:53:29.007183Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T00:53:29.007233Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T00:53:29.009328Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-04-22T00:53:29.009472Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T00:53:29.010376Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:53:29.010461Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:53:29.01049Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:53:32.960946Z","caller":"traceutil/trace.go:171","msg":"trace[1044421541] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:209; }","duration":"200.822129ms","start":"2026-04-22T00:53:32.760101Z","end":"2026-04-22T00:53:32.960923Z","steps":["trace[1044421541] 'read index received' (duration: 158.523117ms)","trace[1044421541] 'applied index is now lower than readState.Index' (duration: 42.297892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:53:32.961116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.991762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-6wphdz\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-04-22T00:53:32.96117Z","caller":"traceutil/trace.go:171","msg":"trace[1783242359] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-6wphdz; range_end:; response_count:1; response_revision:207; }","duration":"201.084973ms","start":"2026-04-22T00:53:32.760075Z","end":"2026-04-22T00:53:32.96116Z","steps":["trace[1783242359] 'agreement among raft nodes before linearized reading' (duration: 200.948311ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:53:32.961295Z","caller":"traceutil/trace.go:171","msg":"trace[569086187] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"201.340427ms","start":"2026-04-22T00:53:32.759942Z","end":"2026-04-22T00:53:32.961282Z","steps":["trace[569086187] 'process raft request' (duration: 200.940381ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:53:32.961243Z","caller":"traceutil/trace.go:171","msg":"trace[834540492] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"212.761668ms","start":"2026-04-22T00:53:32.74845Z","end":"2026-04-22T00:53:32.961212Z","steps":["trace[834540492] 'process raft request' (duration: 170.149631ms)","trace[834540492] 'compare' (duration: 42.141159ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:53:43.865828Z","caller":"traceutil/trace.go:171","msg":"trace[1234908470] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"156.778918ms","start":"2026-04-22T00:53:43.708938Z","end":"2026-04-22T00:53:43.865717Z","steps":["trace[1234908470] 'process raft request' (duration: 156.421293ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:53:44.068098Z","caller":"traceutil/trace.go:171","msg":"trace[425912491] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"191.412086ms","start":"2026-04-22T00:53:43.876668Z","end":"2026-04-22T00:53:44.06808Z","steps":["trace[425912491] 'process raft request' (duration: 191.292464ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:53:44.795323Z","caller":"traceutil/trace.go:171","msg":"trace[1293625993] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"116.796497ms","start":"2026-04-22T00:53:44.678496Z","end":"2026-04-22T00:53:44.795292Z","steps":["trace[1293625993] 'process raft request' (duration: 116.657186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:53:45.169405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.234135ms","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-04-22T00:53:45.169504Z","caller":"traceutil/trace.go:171","msg":"trace[1667234242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:370; }","duration":"148.388137ms","start":"2026-04-22T00:53:45.021094Z","end":"2026-04-22T00:53:45.169482Z","steps":["trace[1667234242] 'range keys from in-memory index tree' (duration: 148.085663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:53:45.169493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.98941ms","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-04-22T00:53:45.169727Z","caller":"traceutil/trace.go:171","msg":"trace[715249403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:370; }","duration":"238.245394ms","start":"2026-04-22T00:53:44.931436Z","end":"2026-04-22T00:53:45.169682Z","steps":["trace[715249403] 'range keys from in-memory index tree' (duration: 237.887357ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:54:01.541856Z","caller":"traceutil/trace.go:171","msg":"trace[1032978904] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"180.898901ms","start":"2026-04-22T00:54:01.360928Z","end":"2026-04-22T00:54:01.541827Z","steps":["trace[1032978904] 'read index received' (duration: 180.600597ms)","trace[1032978904] 'applied index is now lower than readState.Index' (duration: 297.154µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:54:01.541965Z","caller":"traceutil/trace.go:171","msg":"trace[1162068943] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"190.60819ms","start":"2026-04-22T00:54:01.351318Z","end":"2026-04-22T00:54:01.541926Z","steps":["trace[1162068943] 'process raft request' (duration: 190.318776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:01.542016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.057393ms","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-04-22T00:54:01.542052Z","caller":"traceutil/trace.go:171","msg":"trace[1822171930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"181.146015ms","start":"2026-04-22T00:54:01.360895Z","end":"2026-04-22T00:54:01.542041Z","steps":["trace[1822171930] 'agreement among raft nodes before linearized reading' (duration: 181.046744ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:54:01.6641Z","caller":"traceutil/trace.go:171","msg":"trace[215488675] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"118.214608ms","start":"2026-04-22T00:54:01.545863Z","end":"2026-04-22T00:54:01.664078Z","steps":["trace[215488675] 'process raft request' (duration: 100.392788ms)","trace[215488675] 'compare' (duration: 17.466686ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:54:03.369143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201763226914904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:54:03.531079Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.125451841s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T00:54:03.696714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.785248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:54:03.696832Z","caller":"traceutil/trace.go:171","msg":"trace[623866257] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.293006214s","start":"2026-04-22T00:54:02.403808Z","end":"2026-04-22T00:54:03.696814Z","steps":["trace[623866257] 'process raft request' (duration: 1.127566068s)","trace[623866257] 'compare' (duration: 164.679026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:54:03.696895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:02.403791Z","time spent":"1.293074374s","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:54:03.697562Z","caller":"traceutil/trace.go:171","msg":"trace[351386862] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:533; }","duration":"829.032791ms","start":"2026-04-22T00:54:02.868509Z","end":"2026-04-22T00:54:03.697542Z","steps":["trace[351386862] 'read index received' (duration: 662.877958ms)","trace[351386862] 'applied index is now lower than readState.Index' (duration: 166.154073ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:54:03.697862Z","caller":"traceutil/trace.go:171","msg":"trace[1382634185] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.279276646s","start":"2026-04-22T00:54:02.41857Z","end":"2026-04-22T00:54:03.697847Z","steps":["trace[1382634185] 'process raft request' (duration: 1.278801361s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:03.697883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.361895ms","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-04-22T00:54:03.697947Z","caller":"traceutil/trace.go:171","msg":"trace[354140331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"829.472956ms","start":"2026-04-22T00:54:02.86846Z","end":"2026-04-22T00:54:03.697933Z","steps":["trace[354140331] 'agreement among raft nodes before linearized reading' (duration: 829.349464ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:54:03.697997Z","caller":"traceutil/trace.go:171","msg":"trace[864958898] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.270089674s","start":"2026-04-22T00:54:02.427896Z","end":"2026-04-22T00:54:03.697986Z","steps":["trace[864958898] 'process raft request' (duration: 1.269588408s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:03.698005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:02.868437Z","time spent":"829.555548ms","remote":"127.0.0.1:46690","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-04-22T00:54:03.698045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:02.427861Z","time spent":"1.270163885s","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:54:03.697946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:02.41855Z","time spent":"1.279357196s","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:54:03.697867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.264849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:54:03.698165Z","caller":"traceutil/trace.go:171","msg":"trace[1625109029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"427.600263ms","start":"2026-04-22T00:54:03.270546Z","end":"2026-04-22T00:54:03.698146Z","steps":["trace[1625109029] 'agreement among raft nodes before linearized reading' (duration: 427.282138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:03.69821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:03.270522Z","time spent":"427.678453ms","remote":"127.0.0.1:46432","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-04-22T00:54:03.698095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.527319ms","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-04-22T00:54:03.698392Z","caller":"traceutil/trace.go:171","msg":"trace[1199461188] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"152.854052ms","start":"2026-04-22T00:54:03.545525Z","end":"2026-04-22T00:54:03.698379Z","steps":["trace[1199461188] 'agreement among raft nodes before linearized reading' (duration: 152.539259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:09.934241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.764434ms","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-04-22T00:54:09.934302Z","caller":"traceutil/trace.go:171","msg":"trace[1280597715] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"202.871345ms","start":"2026-04-22T00:54:09.731418Z","end":"2026-04-22T00:54:09.934289Z","steps":["trace[1280597715] 'range keys from in-memory index tree' (duration: 202.632893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:09.93444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.213457ms","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-04-22T00:54:09.934463Z","caller":"traceutil/trace.go:171","msg":"trace[922673545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"201.251568ms","start":"2026-04-22T00:54:09.733206Z","end":"2026-04-22T00:54:09.934458Z","steps":["trace[922673545] 'range keys from in-memory index tree' (duration: 201.159207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:09.934565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.399409ms","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-04-22T00:54:09.93459Z","caller":"traceutil/trace.go:171","msg":"trace[206754410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"201.44239ms","start":"2026-04-22T00:54:09.733142Z","end":"2026-04-22T00:54:09.934584Z","steps":["trace[206754410] 'range keys from in-memory index tree' (duration: 201.265947ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:54:10.08812Z","caller":"traceutil/trace.go:171","msg":"trace[519502752] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"149.110238ms","start":"2026-04-22T00:54:09.938986Z","end":"2026-04-22T00:54:10.088096Z","steps":["trace[519502752] 'process raft request' (duration: 127.891624ms)","trace[519502752] 'compare' (duration: 21.048062ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:54:10.088231Z","caller":"traceutil/trace.go:171","msg":"trace[1893040135] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:595; }","duration":"110.468711ms","start":"2026-04-22T00:54:09.977739Z","end":"2026-04-22T00:54:10.088207Z","steps":["trace[1893040135] 'read index received' (duration: 5.714656ms)","trace[1893040135] 'applied index is now lower than readState.Index' (duration: 104.752715ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:54:10.0883Z","caller":"traceutil/trace.go:171","msg":"trace[1949173537] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"145.055361ms","start":"2026-04-22T00:54:09.943213Z","end":"2026-04-22T00:54:10.088269Z","steps":["trace[1949173537] 'process raft request' (duration: 144.842549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:10.088445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.690674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fvzxt\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-22T00:54:10.088474Z","caller":"traceutil/trace.go:171","msg":"trace[843367515] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fvzxt; range_end:; response_count:1; response_revision:577; }","duration":"110.755265ms","start":"2026-04-22T00:54:09.977711Z","end":"2026-04-22T00:54:10.088466Z","steps":["trace[843367515] 'agreement among raft nodes before linearized reading' (duration: 110.594363ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:54:10.144023Z","caller":"traceutil/trace.go:171","msg":"trace[2200280] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"160.01836ms","start":"2026-04-22T00:54:09.983949Z","end":"2026-04-22T00:54:10.143967Z","steps":["trace[2200280] 'process raft request' (duration: 159.1321ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:54:10.502901Z","caller":"traceutil/trace.go:171","msg":"trace[1588945417] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"402.474082ms","start":"2026-04-22T00:54:10.100409Z","end":"2026-04-22T00:54:10.502883Z","steps":["trace[1588945417] 'process raft request' (duration: 402.345619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:10.503019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:10.100381Z","time spent":"402.572862ms","remote":"127.0.0.1:46616","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-04-22T00:54:10.515803Z","caller":"traceutil/trace.go:171","msg":"trace[1216779946] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"314.589332ms","start":"2026-04-22T00:54:10.201195Z","end":"2026-04-22T00:54:10.515784Z","steps":["trace[1216779946] 'process raft request' (duration: 314.39586ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:54:10.515881Z","caller":"traceutil/trace.go:171","msg":"trace[1954705689] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"366.498608ms","start":"2026-04-22T00:54:10.149182Z","end":"2026-04-22T00:54:10.515681Z","steps":["trace[1954705689] 'process raft request' (duration: 366.290426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:10.515916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:10.201167Z","time spent":"314.700903ms","remote":"127.0.0.1:46760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:54:10.516185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:10.149161Z","time spent":"366.759572ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:54:11.770812Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201763226915137,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T00:54:11.817045Z","caller":"traceutil/trace.go:171","msg":"trace[1262241347] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"911.738072ms","start":"2026-04-22T00:54:10.905281Z","end":"2026-04-22T00:54:11.817019Z","steps":["trace[1262241347] 'process raft request' (duration: 911.546289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:11.817203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:10.905252Z","time spent":"911.870004ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:54:11.853273Z","caller":"traceutil/trace.go:171","msg":"trace[1160846825] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"583.128447ms","start":"2026-04-22T00:54:11.27009Z","end":"2026-04-22T00:54:11.853218Z","steps":["trace[1160846825] 'read index received' (duration: 546.748482ms)","trace[1160846825] 'applied index is now lower than readState.Index' (duration: 36.378975ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:54:11.853466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.369472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:54:11.853551Z","caller":"traceutil/trace.go:171","msg":"trace[1585123861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"583.502893ms","start":"2026-04-22T00:54:11.270027Z","end":"2026-04-22T00:54:11.85353Z","steps":["trace[1585123861] 'agreement among raft nodes before linearized reading' (duration: 583.383612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:11.85367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:11.270004Z","time spent":"583.648374ms","remote":"127.0.0.1:46432","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-04-22T00:54:11.853788Z","caller":"traceutil/trace.go:171","msg":"trace[1010478833] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"945.583236ms","start":"2026-04-22T00:54:10.908181Z","end":"2026-04-22T00:54:11.853764Z","steps":["trace[1010478833] 'process raft request' (duration: 939.78548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:11.853974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:10.908167Z","time spent":"945.695847ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:54:24.77453Z","caller":"traceutil/trace.go:171","msg":"trace[2106961890] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"173.028695ms","start":"2026-04-22T00:54:24.601464Z","end":"2026-04-22T00:54:24.774492Z","steps":["trace[2106961890] 'process raft request' (duration: 172.820364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:27.993057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.138115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:54:27.993175Z","caller":"traceutil/trace.go:171","msg":"trace[1185957325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"720.297226ms","start":"2026-04-22T00:54:27.272855Z","end":"2026-04-22T00:54:27.993152Z","steps":["trace[1185957325] 'range keys from in-memory index tree' (duration: 720.061674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:27.993234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:27.272839Z","time spent":"720.379247ms","remote":"127.0.0.1:46432","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-04-22T00:54:27.993433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.780068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-s7f9d\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-22T00:54:27.993529Z","caller":"traceutil/trace.go:171","msg":"trace[2027192952] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-s7f9d; range_end:; response_count:1; response_revision:754; }","duration":"910.911438ms","start":"2026-04-22T00:54:27.082586Z","end":"2026-04-22T00:54:27.993498Z","steps":["trace[2027192952] 'range keys from in-memory index tree' (duration: 910.473135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:27.993606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:27.082562Z","time spent":"911.026869ms","remote":"127.0.0.1:46616","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-s7f9d\" "} {"level":"warn","ts":"2026-04-22T00:54:28.213075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.694732ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-22T00:54:28.213175Z","caller":"traceutil/trace.go:171","msg":"trace[1805956400] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"620.952741ms","start":"2026-04-22T00:54:27.592204Z","end":"2026-04-22T00:54:28.213157Z","steps":["trace[1805956400] 'read index received' (duration: 337.150638ms)","trace[1805956400] 'applied index is now lower than readState.Index' (duration: 283.800663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:54:28.21337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.171484ms","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-04-22T00:54:28.213411Z","caller":"traceutil/trace.go:171","msg":"trace[505016388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:754; }","duration":"621.251294ms","start":"2026-04-22T00:54:27.592145Z","end":"2026-04-22T00:54:28.213396Z","steps":["trace[505016388] 'agreement among raft nodes before linearized reading' (duration: 621.097613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:28.213447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:27.592121Z","time spent":"621.317705ms","remote":"127.0.0.1:46690","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-04-22T00:54:28.220802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.466305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:54:28.220855Z","caller":"traceutil/trace.go:171","msg":"trace[1228173932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"225.556147ms","start":"2026-04-22T00:54:27.995282Z","end":"2026-04-22T00:54:28.220838Z","steps":["trace[1228173932] 'agreement among raft nodes before linearized reading' (duration: 225.478306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:28.221114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.767146ms","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-04-22T00:54:28.221329Z","caller":"traceutil/trace.go:171","msg":"trace[1084193304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"135.994749ms","start":"2026-04-22T00:54:28.085268Z","end":"2026-04-22T00:54:28.221263Z","steps":["trace[1084193304] 'agreement among raft nodes before linearized reading' (duration: 135.612055ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:54:28.222488Z","caller":"traceutil/trace.go:171","msg":"trace[250128527] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"220.701716ms","start":"2026-04-22T00:54:28.00175Z","end":"2026-04-22T00:54:28.222452Z","steps":["trace[250128527] 'process raft request' (duration: 218.844078ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:54:56.820542Z","caller":"traceutil/trace.go:171","msg":"trace[1115867217] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1183; }","duration":"326.256165ms","start":"2026-04-22T00:54:56.494262Z","end":"2026-04-22T00:54:56.820518Z","steps":["trace[1115867217] 'read index received' (duration: 326.027027ms)","trace[1115867217] 'applied index is now lower than readState.Index' (duration: 228.328µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:54:56.820628Z","caller":"traceutil/trace.go:171","msg":"trace[199989170] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"421.647235ms","start":"2026-04-22T00:54:56.398941Z","end":"2026-04-22T00:54:56.820588Z","steps":["trace[199989170] 'process raft request' (duration: 421.404466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:56.820744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.401747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-22T00:54:56.820745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.472383ms","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-04-22T00:54:56.820779Z","caller":"traceutil/trace.go:171","msg":"trace[1605361730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1150; }","duration":"315.47203ms","start":"2026-04-22T00:54:56.505296Z","end":"2026-04-22T00:54:56.820768Z","steps":["trace[1605361730] 'agreement among raft nodes before linearized reading' (duration: 315.357796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:56.820814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:56.505281Z","time spent":"315.525092ms","remote":"127.0.0.1:46690","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-04-22T00:54:56.820803Z","caller":"traceutil/trace.go:171","msg":"trace[1206932959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1150; }","duration":"326.577977ms","start":"2026-04-22T00:54:56.49421Z","end":"2026-04-22T00:54:56.820788Z","steps":["trace[1206932959] 'agreement among raft nodes before linearized reading' (duration: 326.40388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:56.820859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:56.494187Z","time spent":"326.664581ms","remote":"127.0.0.1:46690","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-04-22T00:54:56.820869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.316261ms","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-04-22T00:54:56.820946Z","caller":"traceutil/trace.go:171","msg":"trace[2042477962] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1150; }","duration":"161.439786ms","start":"2026-04-22T00:54:56.659485Z","end":"2026-04-22T00:54:56.820925Z","steps":["trace[2042477962] 'agreement among raft nodes before linearized reading' (duration: 161.307881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:56.821052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.676668ms","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-04-22T00:54:56.821145Z","caller":"traceutil/trace.go:171","msg":"trace[1725751967] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1150; }","duration":"315.809683ms","start":"2026-04-22T00:54:56.505319Z","end":"2026-04-22T00:54:56.821129Z","steps":["trace[1725751967] 'agreement among raft nodes before linearized reading' (duration: 315.539493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:54:56.82118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:56.505307Z","time spent":"315.865105ms","remote":"127.0.0.1:46690","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-04-22T00:54:56.821169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:54:56.398921Z","time spent":"421.79242ms","remote":"127.0.0.1:46690","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-04-22T00:55:13.756451Z","caller":"traceutil/trace.go:171","msg":"trace[81314152] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"201.752372ms","start":"2026-04-22T00:55:13.554666Z","end":"2026-04-22T00:55:13.756418Z","steps":["trace[81314152] 'process raft request' (duration: 194.563329ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:55:19.258597Z","caller":"traceutil/trace.go:171","msg":"trace[2107334593] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"258.187219ms","start":"2026-04-22T00:55:19.000385Z","end":"2026-04-22T00:55:19.258572Z","steps":["trace[2107334593] 'read index received' (duration: 257.869309ms)","trace[2107334593] 'applied index is now lower than readState.Index' (duration: 317µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:55:19.258889Z","caller":"traceutil/trace.go:171","msg":"trace[2107232189] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"339.868498ms","start":"2026-04-22T00:55:18.918773Z","end":"2026-04-22T00:55:19.258642Z","steps":["trace[2107232189] 'process raft request' (duration: 339.58893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:55:19.259078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.859776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-04-22T00:55:19.259104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.680022ms","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-04-22T00:55:19.259139Z","caller":"traceutil/trace.go:171","msg":"trace[161914973] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1402; }","duration":"201.958198ms","start":"2026-04-22T00:55:19.057167Z","end":"2026-04-22T00:55:19.259125Z","steps":["trace[161914973] 'agreement among raft nodes before linearized reading' (duration: 201.801813ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:55:19.259147Z","caller":"traceutil/trace.go:171","msg":"trace[498883840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1402; }","duration":"228.797536ms","start":"2026-04-22T00:55:19.030337Z","end":"2026-04-22T00:55:19.259135Z","steps":["trace[498883840] 'agreement among raft nodes before linearized reading' (duration: 228.649241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:55:19.259226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.919032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-22T00:55:19.259141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.730265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-22T00:55:19.259279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.077216ms","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-04-22T00:55:19.259311Z","caller":"traceutil/trace.go:171","msg":"trace[169013904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1402; }","duration":"258.132037ms","start":"2026-04-22T00:55:19.001167Z","end":"2026-04-22T00:55:19.259299Z","steps":["trace[169013904] 'agreement among raft nodes before linearized reading' (duration: 257.748046ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:55:19.259309Z","caller":"traceutil/trace.go:171","msg":"trace[1082816402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1402; }","duration":"257.024024ms","start":"2026-04-22T00:55:19.002262Z","end":"2026-04-22T00:55:19.259286Z","steps":["trace[1082816402] 'agreement among raft nodes before linearized reading' (duration: 256.771837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:55:19.259578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:55:18.918749Z","time spent":"340.225528ms","remote":"127.0.0.1:46690","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-04-22T00:55:19.259322Z","caller":"traceutil/trace.go:171","msg":"trace[34080438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1402; }","duration":"258.953791ms","start":"2026-04-22T00:55:19.000345Z","end":"2026-04-22T00:55:19.259299Z","steps":["trace[34080438] 'agreement among raft nodes before linearized reading' (duration: 258.525688ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:55:21.760963Z","caller":"traceutil/trace.go:171","msg":"trace[422591427] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"405.169624ms","start":"2026-04-22T00:55:21.355768Z","end":"2026-04-22T00:55:21.760938Z","steps":["trace[422591427] 'process raft request' (duration: 405.00993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:55:21.761102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:55:21.35574Z","time spent":"405.296088ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:55:22.284829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.02719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T00:55:22.284902Z","caller":"traceutil/trace.go:171","msg":"trace[1021198136] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1420; }","duration":"178.158963ms","start":"2026-04-22T00:55:22.106726Z","end":"2026-04-22T00:55:22.284885Z","steps":["trace[1021198136] 'range keys from in-memory index tree' (duration: 177.919547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:55:46.150289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.050758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:55:46.150568Z","caller":"traceutil/trace.go:171","msg":"trace[257350135] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"674.154761ms","start":"2026-04-22T00:55:45.476371Z","end":"2026-04-22T00:55:46.150526Z","steps":["trace[257350135] 'process raft request' (duration: 221.795935ms)","trace[257350135] 'compare' (duration: 451.621858ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:55:46.150574Z","caller":"traceutil/trace.go:171","msg":"trace[1518819778] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"255.76746ms","start":"2026-04-22T00:55:45.894714Z","end":"2026-04-22T00:55:46.150482Z","steps":["trace[1518819778] 'process raft request' (duration: 255.663607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:55:46.150717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:55:45.476348Z","time spent":"674.296464ms","remote":"127.0.0.1:46690","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-04-22T00:55:46.916288Z","caller":"traceutil/trace.go:171","msg":"trace[1156544185] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"540.596507ms","start":"2026-04-22T00:55:46.375664Z","end":"2026-04-22T00:55:46.91626Z","steps":["trace[1156544185] 'process raft request' (duration: 540.409193ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:55:46.9163Z","caller":"traceutil/trace.go:171","msg":"trace[1869849238] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1899; }","duration":"491.152979ms","start":"2026-04-22T00:55:46.425116Z","end":"2026-04-22T00:55:46.916269Z","steps":["trace[1869849238] 'read index received' (duration: 491.141098ms)","trace[1869849238] 'applied index is now lower than readState.Index' (duration: 9.811µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:55:46.916448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:55:46.375645Z","time spent":"540.72648ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:55:46.91666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.524487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b0f94d49-1a9e-4445-b6ec-4dd7d4316c23\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-22T00:55:46.916716Z","caller":"traceutil/trace.go:171","msg":"trace[163584340] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b0f94d49-1a9e-4445-b6ec-4dd7d4316c23; range_end:; response_count:1; response_revision:1828; }","duration":"491.64051ms","start":"2026-04-22T00:55:46.425064Z","end":"2026-04-22T00:55:46.916704Z","steps":["trace[163584340] 'agreement among raft nodes before linearized reading' (duration: 491.316942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:55:46.916918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:55:46.425048Z","time spent":"491.805424ms","remote":"127.0.0.1:46616","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-b0f94d49-1a9e-4445-b6ec-4dd7d4316c23\" "} {"level":"info","ts":"2026-04-22T00:55:46.947965Z","caller":"traceutil/trace.go:171","msg":"trace[563900412] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"489.800177ms","start":"2026-04-22T00:55:46.458139Z","end":"2026-04-22T00:55:46.947939Z","steps":["trace[563900412] 'process raft request' (duration: 489.746976ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:55:46.948088Z","caller":"traceutil/trace.go:171","msg":"trace[1490813315] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"496.750171ms","start":"2026-04-22T00:55:46.451314Z","end":"2026-04-22T00:55:46.948064Z","steps":["trace[1490813315] 'process raft request' (duration: 496.430773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:55:46.948107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:55:46.458124Z","time spent":"489.909319ms","remote":"127.0.0.1:46690","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-04-22T00:55:46.948224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:55:46.451293Z","time spent":"496.860643ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:56:00.798597Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201763226917795,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T00:56:01.168843Z","caller":"traceutil/trace.go:171","msg":"trace[625810544] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"970.63475ms","start":"2026-04-22T00:56:00.198185Z","end":"2026-04-22T00:56:01.168819Z","steps":["trace[625810544] 'process raft request' (duration: 970.492956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:01.168995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:00.198167Z","time spent":"970.740562ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:56:01.298363Z","caller":"traceutil/trace.go:171","msg":"trace[2084108352] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"1.096239639s","start":"2026-04-22T00:56:00.202093Z","end":"2026-04-22T00:56:01.298333Z","steps":["trace[2084108352] 'process raft request' (duration: 1.088298349s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:01.298411Z","caller":"traceutil/trace.go:171","msg":"trace[1118306512] linearizableReadLoop","detail":"{readStateIndex:2020; appliedIndex:2018; }","duration":"1.000072745s","start":"2026-04-22T00:56:00.298315Z","end":"2026-04-22T00:56:01.298388Z","steps":["trace[1118306512] 'read index received' (duration: 870.307165ms)","trace[1118306512] 'applied index is now lower than readState.Index' (duration: 129.76425ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:01.298489Z","caller":"traceutil/trace.go:171","msg":"trace[875130580] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"260.261754ms","start":"2026-04-22T00:56:01.038201Z","end":"2026-04-22T00:56:01.298463Z","steps":["trace[875130580] 'process raft request' (duration: 260.028808ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:01.298748Z","caller":"traceutil/trace.go:171","msg":"trace[2104998403] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"260.255793ms","start":"2026-04-22T00:56:01.038475Z","end":"2026-04-22T00:56:01.29873Z","steps":["trace[2104998403] 'process raft request' (duration: 259.839145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:01.29883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000493453s","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-04-22T00:56:01.299046Z","caller":"traceutil/trace.go:171","msg":"trace[1792229335] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1947; }","duration":"1.000741969s","start":"2026-04-22T00:56:00.298284Z","end":"2026-04-22T00:56:01.299026Z","steps":["trace[1792229335] 'agreement among raft nodes before linearized reading' (duration: 1.00033579s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:01.299139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:00.298269Z","time spent":"1.000856551s","remote":"127.0.0.1:47970","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-04-22T00:56:01.299242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:00.202082Z","time spent":"1.096364032s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:56:09.861673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.318362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:56:09.861789Z","caller":"traceutil/trace.go:171","msg":"trace[2091396694] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"218.172825ms","start":"2026-04-22T00:56:09.64359Z","end":"2026-04-22T00:56:09.861763Z","steps":["trace[2091396694] 'process raft request' (duration: 106.668578ms)","trace[2091396694] 'compare' (duration: 111.075038ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:09.863836Z","caller":"traceutil/trace.go:171","msg":"trace[781818498] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2079; }","duration":"176.076749ms","start":"2026-04-22T00:56:09.687748Z","end":"2026-04-22T00:56:09.863825Z","steps":["trace[781818498] 'read index received' (duration: 62.524491ms)","trace[781818498] 'applied index is now lower than readState.Index' (duration: 113.551668ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:09.863925Z","caller":"traceutil/trace.go:171","msg":"trace[1435838561] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"218.45103ms","start":"2026-04-22T00:56:09.645456Z","end":"2026-04-22T00:56:09.863907Z","steps":["trace[1435838561] 'process raft request' (duration: 218.330797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:09.86395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.191692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:56:09.863982Z","caller":"traceutil/trace.go:171","msg":"trace[634347683] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2005; }","duration":"176.269613ms","start":"2026-04-22T00:56:09.687704Z","end":"2026-04-22T00:56:09.863973Z","steps":["trace[634347683] 'agreement among raft nodes before linearized reading' (duration: 176.191622ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:09.863904Z","caller":"traceutil/trace.go:171","msg":"trace[1219881021] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"220.140634ms","start":"2026-04-22T00:56:09.643733Z","end":"2026-04-22T00:56:09.863874Z","steps":["trace[1219881021] 'process raft request' (duration: 219.97029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:09.868475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.957676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:56:09.868492Z","caller":"traceutil/trace.go:171","msg":"trace[1464823558] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"115.412636ms","start":"2026-04-22T00:56:09.753066Z","end":"2026-04-22T00:56:09.868479Z","steps":["trace[1464823558] 'process raft request' (duration: 115.306154ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:09.868518Z","caller":"traceutil/trace.go:171","msg":"trace[753401463] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2006; }","duration":"145.036948ms","start":"2026-04-22T00:56:09.72347Z","end":"2026-04-22T00:56:09.868507Z","steps":["trace[753401463] 'agreement among raft nodes before linearized reading' (duration: 144.960657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:13.947809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.636115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:56:13.947912Z","caller":"traceutil/trace.go:171","msg":"trace[1764648926] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2036; }","duration":"128.787828ms","start":"2026-04-22T00:56:13.819103Z","end":"2026-04-22T00:56:13.947891Z","steps":["trace[1764648926] 'count revisions from in-memory index tree' (duration: 128.490313ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:14.815426Z","caller":"traceutil/trace.go:171","msg":"trace[139689718] linearizableReadLoop","detail":"{readStateIndex:2118; appliedIndex:2117; }","duration":"199.228216ms","start":"2026-04-22T00:56:14.616179Z","end":"2026-04-22T00:56:14.815407Z","steps":["trace[139689718] 'read index received' (duration: 199.095193ms)","trace[139689718] 'applied index is now lower than readState.Index' (duration: 132.203µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:14.815508Z","caller":"traceutil/trace.go:171","msg":"trace[1705714215] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"229.504293ms","start":"2026-04-22T00:56:14.585972Z","end":"2026-04-22T00:56:14.815476Z","steps":["trace[1705714215] 'process raft request' (duration: 229.315549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:14.816005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.865611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"warn","ts":"2026-04-22T00:56:14.81608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.698676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a887ce2dd918af\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-22T00:56:14.816179Z","caller":"traceutil/trace.go:171","msg":"trace[547435507] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a887ce2dd918af; range_end:; response_count:1; response_revision:2040; }","duration":"200.839887ms","start":"2026-04-22T00:56:14.615323Z","end":"2026-04-22T00:56:14.816163Z","steps":["trace[547435507] 'agreement among raft nodes before linearized reading' (duration: 200.4369ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:14.816118Z","caller":"traceutil/trace.go:171","msg":"trace[1162127513] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2040; }","duration":"150.000603ms","start":"2026-04-22T00:56:14.666058Z","end":"2026-04-22T00:56:14.816059Z","steps":["trace[1162127513] 'agreement among raft nodes before linearized reading' (duration: 149.717038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:15.257583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.040616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:56:15.257906Z","caller":"traceutil/trace.go:171","msg":"trace[1723425660] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"218.106185ms","start":"2026-04-22T00:56:15.039781Z","end":"2026-04-22T00:56:15.257887Z","steps":["trace[1723425660] 'process raft request' (duration: 217.934891ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:15.257988Z","caller":"traceutil/trace.go:171","msg":"trace[819098236] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"436.623677ms","start":"2026-04-22T00:56:14.821337Z","end":"2026-04-22T00:56:15.257961Z","steps":["trace[819098236] 'process raft request' (duration: 225.117292ms)","trace[819098236] 'compare' (duration: 210.76428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:56:15.258165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:14.821317Z","time spent":"436.727968ms","remote":"127.0.0.1:46504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:56:15.258648Z","caller":"traceutil/trace.go:171","msg":"trace[657699500] linearizableReadLoop","detail":"{readStateIndex:2119; appliedIndex:2118; }","duration":"436.919123ms","start":"2026-04-22T00:56:14.821704Z","end":"2026-04-22T00:56:15.258623Z","steps":["trace[657699500] 'read index received' (duration: 224.743735ms)","trace[657699500] 'applied index is now lower than readState.Index' (duration: 212.172928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:56:15.259001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.27987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b0f94d49-1a9e-4445-b6ec-4dd7d4316c23\" ","response":"range_response_count:1 size:1303"} {"level":"info","ts":"2026-04-22T00:56:15.259046Z","caller":"traceutil/trace.go:171","msg":"trace[1981267726] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b0f94d49-1a9e-4445-b6ec-4dd7d4316c23; range_end:; response_count:1; response_revision:2042; }","duration":"437.409003ms","start":"2026-04-22T00:56:14.821626Z","end":"2026-04-22T00:56:15.259035Z","steps":["trace[1981267726] 'agreement among raft nodes before linearized reading' (duration: 437.088606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:15.259108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:14.821601Z","time spent":"437.498165ms","remote":"127.0.0.1:46600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1326,"request content":"key:\"/registry/persistentvolumes/pvc-b0f94d49-1a9e-4445-b6ec-4dd7d4316c23\" "} {"level":"info","ts":"2026-04-22T00:56:15.547174Z","caller":"traceutil/trace.go:171","msg":"trace[2131566880] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"282.361447ms","start":"2026-04-22T00:56:15.264602Z","end":"2026-04-22T00:56:15.546963Z","steps":["trace[2131566880] 'process raft request' (duration: 266.22708ms)","trace[2131566880] 'compare' (duration: 15.540995ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:15.551174Z","caller":"traceutil/trace.go:171","msg":"trace[774499070] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"218.854329ms","start":"2026-04-22T00:56:15.33227Z","end":"2026-04-22T00:56:15.551125Z","steps":["trace[774499070] 'process raft request' (duration: 218.556533ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:15.551561Z","caller":"traceutil/trace.go:171","msg":"trace[154015321] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"146.151462ms","start":"2026-04-22T00:56:15.405378Z","end":"2026-04-22T00:56:15.551529Z","steps":["trace[154015321] 'process raft request' (duration: 145.623091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:18.525713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201763226918057,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T00:56:18.576985Z","caller":"traceutil/trace.go:171","msg":"trace[1893948632] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"890.932023ms","start":"2026-04-22T00:56:17.686028Z","end":"2026-04-22T00:56:18.57696Z","steps":["trace[1893948632] 'process raft request' (duration: 890.73482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:18.577145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:17.686007Z","time spent":"891.056136ms","remote":"127.0.0.1:46690","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-04-22T00:56:18.592614Z","caller":"traceutil/trace.go:171","msg":"trace[827224699] linearizableReadLoop","detail":"{readStateIndex:2139; appliedIndex:2137; }","duration":"567.395728ms","start":"2026-04-22T00:56:18.025199Z","end":"2026-04-22T00:56:18.592594Z","steps":["trace[827224699] 'read index received' (duration: 551.553901ms)","trace[827224699] 'applied index is now lower than readState.Index' (duration: 15.840837ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:18.592696Z","caller":"traceutil/trace.go:171","msg":"trace[709657906] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"820.871101ms","start":"2026-04-22T00:56:17.77179Z","end":"2026-04-22T00:56:18.592661Z","steps":["trace[709657906] 'process raft request' (duration: 820.669317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:18.592821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.029302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-22T00:56:18.592844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:17.771765Z","time spent":"821.000304ms","remote":"127.0.0.1:46690","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-04-22T00:56:18.592863Z","caller":"traceutil/trace.go:171","msg":"trace[687334737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2060; }","duration":"565.109813ms","start":"2026-04-22T00:56:18.027743Z","end":"2026-04-22T00:56:18.592853Z","steps":["trace[687334737] 'agreement among raft nodes before linearized reading' (duration: 564.97065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:18.592845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.638392ms","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-04-22T00:56:18.592896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:18.02773Z","time spent":"565.157344ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T00:56:18.592911Z","caller":"traceutil/trace.go:171","msg":"trace[1472825077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2060; }","duration":"567.742914ms","start":"2026-04-22T00:56:18.025151Z","end":"2026-04-22T00:56:18.592894Z","steps":["trace[1472825077] 'agreement among raft nodes before linearized reading' (duration: 567.562711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:18.592962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:18.025131Z","time spent":"567.814895ms","remote":"127.0.0.1:46690","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-04-22T00:56:18.593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.581875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:56:18.593017Z","caller":"traceutil/trace.go:171","msg":"trace[1709372637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2060; }","duration":"322.644457ms","start":"2026-04-22T00:56:18.270366Z","end":"2026-04-22T00:56:18.593011Z","steps":["trace[1709372637] 'agreement among raft nodes before linearized reading' (duration: 322.606086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:18.593033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:18.270342Z","time spent":"322.687208ms","remote":"127.0.0.1:46432","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-04-22T00:56:18.593082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.019527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-22T00:56:18.593123Z","caller":"traceutil/trace.go:171","msg":"trace[1994512710] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2060; }","duration":"275.104617ms","start":"2026-04-22T00:56:18.318007Z","end":"2026-04-22T00:56:18.593112Z","steps":["trace[1994512710] 'agreement among raft nodes before linearized reading' (duration: 274.960215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:18.593167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.227085ms","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-04-22T00:56:18.593219Z","caller":"traceutil/trace.go:171","msg":"trace[15455341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2060; }","duration":"565.318587ms","start":"2026-04-22T00:56:18.027886Z","end":"2026-04-22T00:56:18.593204Z","steps":["trace[15455341] 'agreement among raft nodes before linearized reading' (duration: 565.189955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:18.593265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:18.027858Z","time spent":"565.395668ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T00:56:18.725049Z","caller":"traceutil/trace.go:171","msg":"trace[1272999377] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"127.442893ms","start":"2026-04-22T00:56:18.59758Z","end":"2026-04-22T00:56:18.725023Z","steps":["trace[1272999377] 'process raft request' (duration: 102.668724ms)","trace[1272999377] 'compare' (duration: 24.344661ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:18.7253Z","caller":"traceutil/trace.go:171","msg":"trace[652877182] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"127.223879ms","start":"2026-04-22T00:56:18.598052Z","end":"2026-04-22T00:56:18.725276Z","steps":["trace[652877182] 'process raft request' (duration: 127.135167ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:18.725327Z","caller":"traceutil/trace.go:171","msg":"trace[412503195] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"127.718968ms","start":"2026-04-22T00:56:18.597586Z","end":"2026-04-22T00:56:18.725305Z","steps":["trace[412503195] 'process raft request' (duration: 127.350562ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:19.532042Z","caller":"traceutil/trace.go:171","msg":"trace[164842585] linearizableReadLoop","detail":"{readStateIndex:2144; appliedIndex:2143; }","duration":"106.377735ms","start":"2026-04-22T00:56:19.425642Z","end":"2026-04-22T00:56:19.53202Z","steps":["trace[164842585] 'read index received' (duration: 106.240963ms)","trace[164842585] 'applied index is now lower than readState.Index' (duration: 136.012µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:19.53211Z","caller":"traceutil/trace.go:171","msg":"trace[552067515] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"224.21304ms","start":"2026-04-22T00:56:19.307865Z","end":"2026-04-22T00:56:19.532078Z","steps":["trace[552067515] 'process raft request' (duration: 224.027837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:19.532488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.841435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"info","ts":"2026-04-22T00:56:19.532686Z","caller":"traceutil/trace.go:171","msg":"trace[575030677] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2065; }","duration":"106.972257ms","start":"2026-04-22T00:56:19.42558Z","end":"2026-04-22T00:56:19.532552Z","steps":["trace[575030677] 'agreement among raft nodes before linearized reading' (duration: 106.533219ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:19.759779Z","caller":"traceutil/trace.go:171","msg":"trace[1997195311] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"197.055689ms","start":"2026-04-22T00:56:19.562703Z","end":"2026-04-22T00:56:19.759759Z","steps":["trace[1997195311] 'process raft request' (duration: 154.770106ms)","trace[1997195311] 'compare' (duration: 41.910176ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:19.760076Z","caller":"traceutil/trace.go:171","msg":"trace[1392731600] linearizableReadLoop","detail":"{readStateIndex:2148; appliedIndex:2144; }","duration":"173.486266ms","start":"2026-04-22T00:56:19.586568Z","end":"2026-04-22T00:56:19.760054Z","steps":["trace[1392731600] 'read index received' (duration: 130.919027ms)","trace[1392731600] 'applied index is now lower than readState.Index' (duration: 42.566339ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:19.760317Z","caller":"traceutil/trace.go:171","msg":"trace[1580066960] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"196.981127ms","start":"2026-04-22T00:56:19.563325Z","end":"2026-04-22T00:56:19.760306Z","steps":["trace[1580066960] 'process raft request' (duration: 196.401307ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:19.760398Z","caller":"traceutil/trace.go:171","msg":"trace[1540528710] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"196.399567ms","start":"2026-04-22T00:56:19.563981Z","end":"2026-04-22T00:56:19.760381Z","steps":["trace[1540528710] 'process raft request' (duration: 195.970768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:19.760407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.814212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:56:19.760469Z","caller":"traceutil/trace.go:171","msg":"trace[274797151] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2069; }","duration":"173.908164ms","start":"2026-04-22T00:56:19.586534Z","end":"2026-04-22T00:56:19.760442Z","steps":["trace[274797151] 'agreement among raft nodes before linearized reading' (duration: 173.820562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:19.760572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.291382ms","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-04-22T00:56:19.760611Z","caller":"traceutil/trace.go:171","msg":"trace[1264234784] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2069; }","duration":"161.365942ms","start":"2026-04-22T00:56:19.599234Z","end":"2026-04-22T00:56:19.7606Z","steps":["trace[1264234784] 'agreement among raft nodes before linearized reading' (duration: 161.23299ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:19.760692Z","caller":"traceutil/trace.go:171","msg":"trace[578777167] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"196.006279ms","start":"2026-04-22T00:56:19.564609Z","end":"2026-04-22T00:56:19.760615Z","steps":["trace[578777167] 'process raft request' (duration: 195.406287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:19.760823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.703725ms","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-04-22T00:56:19.760862Z","caller":"traceutil/trace.go:171","msg":"trace[1154743678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2069; }","duration":"102.768946ms","start":"2026-04-22T00:56:19.658084Z","end":"2026-04-22T00:56:19.760853Z","steps":["trace[1154743678] 'agreement among raft nodes before linearized reading' (duration: 102.655764ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:19.978342Z","caller":"traceutil/trace.go:171","msg":"trace[1741696989] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"212.968335ms","start":"2026-04-22T00:56:19.765351Z","end":"2026-04-22T00:56:19.978319Z","steps":["trace[1741696989] 'process raft request' (duration: 212.882313ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:19.978551Z","caller":"traceutil/trace.go:171","msg":"trace[1547531640] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"215.700147ms","start":"2026-04-22T00:56:19.762823Z","end":"2026-04-22T00:56:19.978523Z","steps":["trace[1547531640] 'process raft request' (duration: 190.665786ms)","trace[1547531640] 'compare' (duration: 24.577683ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:56:21.230406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201763226918084,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:56:21.731238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201763226918084,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:56:21.78636Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.202340978s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T00:56:21.786876Z","caller":"traceutil/trace.go:171","msg":"trace[1657056614] linearizableReadLoop","detail":"{readStateIndex:2151; appliedIndex:2150; }","duration":"1.056883448s","start":"2026-04-22T00:56:20.729949Z","end":"2026-04-22T00:56:21.786833Z","steps":["trace[1657056614] 'read index received' (duration: 1.056730305s)","trace[1657056614] 'applied index is now lower than readState.Index' (duration: 152.253µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:21.786985Z","caller":"traceutil/trace.go:171","msg":"trace[1441140832] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"1.202990172s","start":"2026-04-22T00:56:20.583936Z","end":"2026-04-22T00:56:21.786926Z","steps":["trace[1441140832] 'process raft request' (duration: 1.202762918s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:21.787125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057161424s","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-04-22T00:56:21.787155Z","caller":"traceutil/trace.go:171","msg":"trace[1745418368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2072; }","duration":"1.057238064s","start":"2026-04-22T00:56:20.72991Z","end":"2026-04-22T00:56:21.787148Z","steps":["trace[1745418368] 'agreement among raft nodes before linearized reading' (duration: 1.05705472s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:21.787181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:20.729893Z","time spent":"1.057282426s","remote":"127.0.0.1:46690","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-04-22T00:56:21.787257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:20.583925Z","time spent":"1.203184826s","remote":"127.0.0.1:46690","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-04-22T00:56:21.787296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057325697s","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-04-22T00:56:21.787453Z","caller":"traceutil/trace.go:171","msg":"trace[2009707668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2072; }","duration":"1.05751423s","start":"2026-04-22T00:56:20.729922Z","end":"2026-04-22T00:56:21.787436Z","steps":["trace[2009707668] 'agreement among raft nodes before linearized reading' (duration: 1.05701277s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:21.78753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:20.729912Z","time spent":"1.057607251s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T00:56:21.841072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110776513s","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-04-22T00:56:21.841162Z","caller":"traceutil/trace.go:171","msg":"trace[615607211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2076; }","duration":"1.110913486s","start":"2026-04-22T00:56:20.730231Z","end":"2026-04-22T00:56:21.841144Z","steps":["trace[615607211] 'agreement among raft nodes before linearized reading' (duration: 1.11067414s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:21.841206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:20.730213Z","time spent":"1.110985067s","remote":"127.0.0.1:46690","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-04-22T00:56:21.841279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.593715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:56:21.841294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.26495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:56:21.841314Z","caller":"traceutil/trace.go:171","msg":"trace[1692574282] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2076; }","duration":"264.684847ms","start":"2026-04-22T00:56:21.576622Z","end":"2026-04-22T00:56:21.841307Z","steps":["trace[1692574282] 'agreement among raft nodes before linearized reading' (duration: 264.601485ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:21.841323Z","caller":"traceutil/trace.go:171","msg":"trace[565836930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2076; }","duration":"571.329312ms","start":"2026-04-22T00:56:21.269984Z","end":"2026-04-22T00:56:21.841313Z","steps":["trace[565836930] 'agreement among raft nodes before linearized reading' (duration: 571.280621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:21.841341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:21.269963Z","time spent":"571.372872ms","remote":"127.0.0.1:46432","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-04-22T00:56:21.841512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073971069s","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-04-22T00:56:21.841684Z","caller":"traceutil/trace.go:171","msg":"trace[1204202113] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2076; }","duration":"1.074179343s","start":"2026-04-22T00:56:20.767481Z","end":"2026-04-22T00:56:21.84166Z","steps":["trace[1204202113] 'agreement among raft nodes before linearized reading' (duration: 1.073875727s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:21.841795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:20.767458Z","time spent":"1.074321436s","remote":"127.0.0.1:47970","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-04-22T00:56:21.982261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.195171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:56:21.982475Z","caller":"traceutil/trace.go:171","msg":"trace[172283883] linearizableReadLoop","detail":"{readStateIndex:2157; appliedIndex:2155; }","duration":"139.055266ms","start":"2026-04-22T00:56:21.843391Z","end":"2026-04-22T00:56:21.982446Z","steps":["trace[172283883] 'read index received' (duration: 12.6043ms)","trace[172283883] 'applied index is now lower than readState.Index' (duration: 126.450176ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:21.982537Z","caller":"traceutil/trace.go:171","msg":"trace[49867135] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"191.31097ms","start":"2026-04-22T00:56:21.791189Z","end":"2026-04-22T00:56:21.9825Z","steps":["trace[49867135] 'process raft request' (duration: 64.794783ms)","trace[49867135] 'compare' (duration: 126.081009ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:21.98259Z","caller":"traceutil/trace.go:171","msg":"trace[1188764962] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"190.352182ms","start":"2026-04-22T00:56:21.792215Z","end":"2026-04-22T00:56:21.982567Z","steps":["trace[1188764962] 'process raft request' (duration: 190.147117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:21.982884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.455713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:56:21.983011Z","caller":"traceutil/trace.go:171","msg":"trace[698678812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2078; }","duration":"139.636986ms","start":"2026-04-22T00:56:21.843352Z","end":"2026-04-22T00:56:21.982989Z","steps":["trace[698678812] 'agreement among raft nodes before linearized reading' (duration: 139.364851ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:21.982944Z","caller":"traceutil/trace.go:171","msg":"trace[1593914320] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"136.10226ms","start":"2026-04-22T00:56:21.846824Z","end":"2026-04-22T00:56:21.982926Z","steps":["trace[1593914320] 'process raft request' (duration: 135.599981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:22.770835Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201763226918097,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:56:24.216915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294852024s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:56:24.217013Z","caller":"traceutil/trace.go:171","msg":"trace[848951444] linearizableReadLoop","detail":"{readStateIndex:2161; appliedIndex:2160; }","duration":"1.94650246s","start":"2026-04-22T00:56:22.270494Z","end":"2026-04-22T00:56:24.216997Z","steps":["trace[848951444] 'read index received' (duration: 651.479712ms)","trace[848951444] 'applied index is now lower than readState.Index' (duration: 1.295021288s)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:24.217062Z","caller":"traceutil/trace.go:171","msg":"trace[1389498950] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"2.20694355s","start":"2026-04-22T00:56:22.010075Z","end":"2026-04-22T00:56:24.217019Z","steps":["trace[1389498950] 'process raft request' (duration: 911.910883ms)","trace[1389498950] 'compare' (duration: 1.29463791s)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:56:24.217257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.946768875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:56:24.217255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:22.010052Z","time spent":"2.207092993s","remote":"127.0.0.1:46460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:56:24.217291Z","caller":"traceutil/trace.go:171","msg":"trace[334874523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2081; }","duration":"1.946837567s","start":"2026-04-22T00:56:22.270443Z","end":"2026-04-22T00:56:24.21728Z","steps":["trace[334874523] 'agreement among raft nodes before linearized reading' (duration: 1.946660893s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.217326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:22.270422Z","time spent":"1.946895177s","remote":"127.0.0.1:46432","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-04-22T00:56:24.219256Z","caller":"traceutil/trace.go:171","msg":"trace[952190526] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"372.669659ms","start":"2026-04-22T00:56:23.846557Z","end":"2026-04-22T00:56:24.219227Z","steps":["trace[952190526] 'process raft request' (duration: 372.493465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.219578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:23.846529Z","time spent":"372.811551ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:56:24.219621Z","caller":"traceutil/trace.go:171","msg":"trace[1194797654] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"372.987155ms","start":"2026-04-22T00:56:23.846601Z","end":"2026-04-22T00:56:24.219588Z","steps":["trace[1194797654] 'process raft request' (duration: 372.558896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.220137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:23.846576Z","time spent":"373.482904ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:56:24.222282Z","caller":"traceutil/trace.go:171","msg":"trace[547924715] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"374.667306ms","start":"2026-04-22T00:56:23.847588Z","end":"2026-04-22T00:56:24.222256Z","steps":["trace[547924715] 'process raft request' (duration: 374.511303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.22242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:23.847578Z","time spent":"374.768037ms","remote":"127.0.0.1:46690","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-04-22T00:56:24.222513Z","caller":"traceutil/trace.go:171","msg":"trace[954431984] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"374.857379ms","start":"2026-04-22T00:56:23.847642Z","end":"2026-04-22T00:56:24.2225Z","steps":["trace[954431984] 'process raft request' (duration: 374.580794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.222885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.077308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T00:56:24.222917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:23.847638Z","time spent":"375.252857ms","remote":"127.0.0.1:46690","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-04-22T00:56:24.222918Z","caller":"traceutil/trace.go:171","msg":"trace[1114327150] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2085; }","duration":"989.146169ms","start":"2026-04-22T00:56:23.233761Z","end":"2026-04-22T00:56:24.222908Z","steps":["trace[1114327150] 'agreement among raft nodes before linearized reading' (duration: 989.071308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.223094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:23.233739Z","time spent":"989.335032ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-22T00:56:24.222706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.042226ms","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-04-22T00:56:24.223152Z","caller":"traceutil/trace.go:171","msg":"trace[1211722700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2085; }","duration":"194.538365ms","start":"2026-04-22T00:56:24.028605Z","end":"2026-04-22T00:56:24.223144Z","steps":["trace[1211722700] 'agreement among raft nodes before linearized reading' (duration: 193.945124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.223529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.938495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T00:56:24.222753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.564327ms","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-04-22T00:56:24.223573Z","caller":"traceutil/trace.go:171","msg":"trace[1637605774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2085; }","duration":"432.017896ms","start":"2026-04-22T00:56:23.791543Z","end":"2026-04-22T00:56:24.223561Z","steps":["trace[1637605774] 'agreement among raft nodes before linearized reading' (duration: 431.022146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.223603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:23.791522Z","time spent":"432.073697ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T00:56:24.22361Z","caller":"traceutil/trace.go:171","msg":"trace[1177451345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2085; }","duration":"235.448924ms","start":"2026-04-22T00:56:23.988138Z","end":"2026-04-22T00:56:24.223587Z","steps":["trace[1177451345] 'agreement among raft nodes before linearized reading' (duration: 234.399884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.222788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.127659ms","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-04-22T00:56:24.223701Z","caller":"traceutil/trace.go:171","msg":"trace[1976207336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2085; }","duration":"235.089457ms","start":"2026-04-22T00:56:23.988597Z","end":"2026-04-22T00:56:24.223686Z","steps":["trace[1976207336] 'agreement among raft nodes before linearized reading' (duration: 234.146209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.222798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.491687ms","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-04-22T00:56:24.22381Z","caller":"traceutil/trace.go:171","msg":"trace[491701768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2085; }","duration":"234.524396ms","start":"2026-04-22T00:56:23.989272Z","end":"2026-04-22T00:56:24.223796Z","steps":["trace[491701768] 'agreement among raft nodes before linearized reading' (duration: 233.444906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.222799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374645695s","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-04-22T00:56:24.22392Z","caller":"traceutil/trace.go:171","msg":"trace[1064401003] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2085; }","duration":"1.375789966s","start":"2026-04-22T00:56:22.848116Z","end":"2026-04-22T00:56:24.223906Z","steps":["trace[1064401003] 'agreement among raft nodes before linearized reading' (duration: 1.374585094s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.222833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.769096502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T00:56:24.224063Z","caller":"traceutil/trace.go:171","msg":"trace[24427974] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2085; }","duration":"1.770358284s","start":"2026-04-22T00:56:22.453692Z","end":"2026-04-22T00:56:24.224051Z","steps":["trace[24427974] 'agreement among raft nodes before linearized reading' (duration: 1.769116032s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.224049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:22.848091Z","time spent":"1.375945409s","remote":"127.0.0.1:47970","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-04-22T00:56:24.224115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:22.453664Z","time spent":"1.770435196s","remote":"127.0.0.1:46870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-22T00:56:24.222823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.171752ms","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-04-22T00:56:24.225842Z","caller":"traceutil/trace.go:171","msg":"trace[765342022] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2085; }","duration":"553.185508ms","start":"2026-04-22T00:56:23.672642Z","end":"2026-04-22T00:56:24.225828Z","steps":["trace[765342022] 'agreement among raft nodes before linearized reading' (duration: 550.146221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.225894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:23.672551Z","time spent":"553.33001ms","remote":"127.0.0.1:55236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"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-04-22T00:56:24.222685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.777501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T00:56:24.226058Z","caller":"traceutil/trace.go:171","msg":"trace[1021255006] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2085; }","duration":"125.199337ms","start":"2026-04-22T00:56:24.10085Z","end":"2026-04-22T00:56:24.22605Z","steps":["trace[1021255006] 'agreement among raft nodes before linearized reading' (duration: 121.725392ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:56:24.538945Z","caller":"traceutil/trace.go:171","msg":"trace[134452797] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"311.190591ms","start":"2026-04-22T00:56:24.22771Z","end":"2026-04-22T00:56:24.5389Z","steps":["trace[134452797] 'process raft request' (duration: 310.970437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.539366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:24.227696Z","time spent":"311.553448ms","remote":"127.0.0.1:46690","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-04-22T00:56:24.545851Z","caller":"traceutil/trace.go:171","msg":"trace[862528456] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2165; }","duration":"314.022374ms","start":"2026-04-22T00:56:24.231805Z","end":"2026-04-22T00:56:24.545828Z","steps":["trace[862528456] 'read index received' (duration: 306.945342ms)","trace[862528456] 'applied index is now lower than readState.Index' (duration: 7.076032ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:24.546024Z","caller":"traceutil/trace.go:171","msg":"trace[1492075286] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"317.931667ms","start":"2026-04-22T00:56:24.228072Z","end":"2026-04-22T00:56:24.546004Z","steps":["trace[1492075286] 'process raft request' (duration: 317.651202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.546662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:24.228066Z","time spent":"318.512709ms","remote":"127.0.0.1:46690","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-04-22T00:56:24.546121Z","caller":"traceutil/trace.go:171","msg":"trace[1789002691] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"318.100731ms","start":"2026-04-22T00:56:24.228008Z","end":"2026-04-22T00:56:24.546109Z","steps":["trace[1789002691] 'process raft request' (duration: 317.593472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.546968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:24.227993Z","time spent":"318.898756ms","remote":"127.0.0.1:46690","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-04-22T00:56:24.546161Z","caller":"traceutil/trace.go:171","msg":"trace[2011014898] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"317.96807ms","start":"2026-04-22T00:56:24.228186Z","end":"2026-04-22T00:56:24.546154Z","steps":["trace[2011014898] 'process raft request' (duration: 317.569232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.547138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:24.228178Z","time spent":"318.926787ms","remote":"127.0.0.1:46690","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-04-22T00:56:24.546196Z","caller":"traceutil/trace.go:171","msg":"trace[1360259339] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"316.138874ms","start":"2026-04-22T00:56:24.230051Z","end":"2026-04-22T00:56:24.54619Z","steps":["trace[1360259339] 'process raft request' (duration: 315.739656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.54742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:24.230033Z","time spent":"317.240585ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:56:24.546418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.596785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:56:24.547547Z","caller":"traceutil/trace.go:171","msg":"trace[834498292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2090; }","duration":"315.745006ms","start":"2026-04-22T00:56:24.231786Z","end":"2026-04-22T00:56:24.547531Z","steps":["trace[834498292] 'agreement among raft nodes before linearized reading' (duration: 314.564395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:24.548097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:24.231778Z","time spent":"316.301127ms","remote":"127.0.0.1:46432","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-04-22T00:56:25.257789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.133351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-04-22T00:56:25.257885Z","caller":"traceutil/trace.go:171","msg":"trace[208903390] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2090; }","duration":"546.266442ms","start":"2026-04-22T00:56:24.711596Z","end":"2026-04-22T00:56:25.257862Z","steps":["trace[208903390] 'range keys from in-memory index tree' (duration: 545.977077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:25.257917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:24.711567Z","time spent":"546.342254ms","remote":"127.0.0.1:46630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2009,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-04-22T00:56:25.25848Z","caller":"traceutil/trace.go:171","msg":"trace[922731634] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2170; }","duration":"150.969566ms","start":"2026-04-22T00:56:25.107482Z","end":"2026-04-22T00:56:25.258451Z","steps":["trace[922731634] 'read index received' (duration: 139.687605ms)","trace[922731634] 'applied index is now lower than readState.Index' (duration: 11.28068ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:56:25.258699Z","caller":"traceutil/trace.go:171","msg":"trace[709501671] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"378.804725ms","start":"2026-04-22T00:56:24.879833Z","end":"2026-04-22T00:56:25.258638Z","steps":["trace[709501671] 'process raft request' (duration: 367.340621ms)","trace[709501671] 'compare' (duration: 10.231281ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:56:25.258778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.288291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a887ce2dd918af\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-22T00:56:25.258884Z","caller":"traceutil/trace.go:171","msg":"trace[2055796075] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a887ce2dd918af; range_end:; response_count:1; response_revision:2091; }","duration":"151.398943ms","start":"2026-04-22T00:56:25.107429Z","end":"2026-04-22T00:56:25.258828Z","steps":["trace[2055796075] 'agreement among raft nodes before linearized reading' (duration: 151.190599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:56:25.259092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:56:24.879802Z","time spent":"379.012819ms","remote":"127.0.0.1:46690","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-04-22T00:58:03.882247Z","caller":"traceutil/trace.go:171","msg":"trace[81096351] transaction","detail":"{read_only:false; response_revision:2659; number_of_response:1; }","duration":"150.385892ms","start":"2026-04-22T00:58:03.731833Z","end":"2026-04-22T00:58:03.882219Z","steps":["trace[81096351] 'process raft request' (duration: 149.955956ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:58:11.056757Z","caller":"traceutil/trace.go:171","msg":"trace[776191853] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"688.520826ms","start":"2026-04-22T00:58:10.368216Z","end":"2026-04-22T00:58:11.056737Z","steps":["trace[776191853] 'process raft request' (duration: 688.399374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:58:11.056883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:58:10.368195Z","time spent":"688.620438ms","remote":"127.0.0.1:46690","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-04-22T00:58:11.077339Z","caller":"traceutil/trace.go:171","msg":"trace[1604731295] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"571.901934ms","start":"2026-04-22T00:58:10.50541Z","end":"2026-04-22T00:58:11.077312Z","steps":["trace[1604731295] 'process raft request' (duration: 571.61107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:58:11.077549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:58:10.505375Z","time spent":"572.048046ms","remote":"127.0.0.1:46690","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-04-22T00:58:11.077551Z","caller":"traceutil/trace.go:171","msg":"trace[587722439] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"485.736106ms","start":"2026-04-22T00:58:10.591801Z","end":"2026-04-22T00:58:11.077537Z","steps":["trace[587722439] 'process raft request' (duration: 485.447442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:58:11.077631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:58:10.591767Z","time spent":"485.822296ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:58:11.077698Z","caller":"traceutil/trace.go:171","msg":"trace[948202519] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"409.84644ms","start":"2026-04-22T00:58:10.667846Z","end":"2026-04-22T00:58:11.077692Z","steps":["trace[948202519] 'process raft request' (duration: 409.638987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:58:11.077725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:58:10.667777Z","time spent":"409.934621ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:58:11.077785Z","caller":"traceutil/trace.go:171","msg":"trace[1587836758] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"403.997129ms","start":"2026-04-22T00:58:10.673782Z","end":"2026-04-22T00:58:11.077779Z","steps":["trace[1587836758] 'process raft request' (duration: 403.885216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:58:11.077813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:58:10.673763Z","time spent":"404.036119ms","remote":"127.0.0.1:46690","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-04-22T00:58:11.077814Z","caller":"traceutil/trace.go:171","msg":"trace[6346312] linearizableReadLoop","detail":"{readStateIndex:2851; appliedIndex:2846; }","duration":"324.650915ms","start":"2026-04-22T00:58:10.753134Z","end":"2026-04-22T00:58:11.077785Z","steps":["trace[6346312] 'read index received' (duration: 303.681003ms)","trace[6346312] 'applied index is now lower than readState.Index' (duration: 20.961632ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:58:11.077811Z","caller":"traceutil/trace.go:171","msg":"trace[441281796] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"409.184691ms","start":"2026-04-22T00:58:10.668593Z","end":"2026-04-22T00:58:11.077778Z","steps":["trace[441281796] 'process raft request' (duration: 409.031938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:58:11.077968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.811117ms","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":"warn","ts":"2026-04-22T00:58:11.078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:58:10.668542Z","time spent":"409.381354ms","remote":"127.0.0.1:46690","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-04-22T00:58:11.078022Z","caller":"traceutil/trace.go:171","msg":"trace[527421508] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2747; }","duration":"324.927499ms","start":"2026-04-22T00:58:10.75308Z","end":"2026-04-22T00:58:11.078008Z","steps":["trace[527421508] 'agreement among raft nodes before linearized reading' (duration: 324.806967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:58:11.078068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:58:10.753058Z","time spent":"324.999859ms","remote":"127.0.0.1:49636","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":"info","ts":"2026-04-22T00:58:43.418103Z","caller":"traceutil/trace.go:171","msg":"trace[1136511266] linearizableReadLoop","detail":"{readStateIndex:3036; appliedIndex:3035; }","duration":"148.493098ms","start":"2026-04-22T00:58:43.269574Z","end":"2026-04-22T00:58:43.418067Z","steps":["trace[1136511266] 'read index received' (duration: 100.128677ms)","trace[1136511266] 'applied index is now lower than readState.Index' (duration: 48.363361ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:58:43.418173Z","caller":"traceutil/trace.go:171","msg":"trace[1211033285] transaction","detail":"{read_only:false; response_revision:2925; number_of_response:1; }","duration":"191.79006ms","start":"2026-04-22T00:58:43.226339Z","end":"2026-04-22T00:58:43.418129Z","steps":["trace[1211033285] 'process raft request' (duration: 143.419859ms)","trace[1211033285] 'compare' (duration: 48.194919ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:58:43.418449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.838803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T00:58:43.418494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.973629ms","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-04-22T00:58:43.418534Z","caller":"traceutil/trace.go:171","msg":"trace[1216888369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2925; }","duration":"148.985145ms","start":"2026-04-22T00:58:43.269531Z","end":"2026-04-22T00:58:43.418516Z","steps":["trace[1216888369] 'agreement among raft nodes before linearized reading' (duration: 148.669051ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:58:43.418566Z","caller":"traceutil/trace.go:171","msg":"trace[1699314484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2925; }","duration":"107.05777ms","start":"2026-04-22T00:58:43.311494Z","end":"2026-04-22T00:58:43.418552Z","steps":["trace[1699314484] 'agreement among raft nodes before linearized reading' (duration: 106.876018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:58:43.418733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.809527ms","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-04-22T00:58:43.418766Z","caller":"traceutil/trace.go:171","msg":"trace[1322659468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2925; }","duration":"106.861937ms","start":"2026-04-22T00:58:43.311898Z","end":"2026-04-22T00:58:43.41876Z","steps":["trace[1322659468] 'agreement among raft nodes before linearized reading' (duration: 106.790876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:58:43.418766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.266013ms","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-04-22T00:58:43.418826Z","caller":"traceutil/trace.go:171","msg":"trace[580429468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2925; }","duration":"107.374245ms","start":"2026-04-22T00:58:43.311441Z","end":"2026-04-22T00:58:43.418815Z","steps":["trace[580429468] 'agreement among raft nodes before linearized reading' (duration: 107.232213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:58:43.418801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.860293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:58:43.419048Z","caller":"traceutil/trace.go:171","msg":"trace[438342096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2925; }","duration":"149.468682ms","start":"2026-04-22T00:58:43.269547Z","end":"2026-04-22T00:58:43.419016Z","steps":["trace[438342096] 'agreement among raft nodes before linearized reading' (duration: 148.797303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:58:43.419128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.76459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-22T00:58:43.419209Z","caller":"traceutil/trace.go:171","msg":"trace[87395203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2925; }","duration":"107.861131ms","start":"2026-04-22T00:58:43.31133Z","end":"2026-04-22T00:58:43.419191Z","steps":["trace[87395203] 'agreement among raft nodes before linearized reading' (duration: 107.555457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:00:42.874215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201763226922006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T01:00:43.374792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201763226922006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T01:00:43.386456Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.276445512s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T01:00:43.387035Z","caller":"traceutil/trace.go:171","msg":"trace[32996068] transaction","detail":"{read_only:false; response_revision:3636; number_of_response:1; }","duration":"1.277113699s","start":"2026-04-22T01:00:42.109898Z","end":"2026-04-22T01:00:43.387012Z","steps":["trace[32996068] 'process raft request' (duration: 1.276939445s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:00:43.387427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:00:42.109873Z","time spent":"1.277238852s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T01:00:43.390624Z","caller":"traceutil/trace.go:171","msg":"trace[182256136] linearizableReadLoop","detail":"{readStateIndex:3777; appliedIndex:3774; }","duration":"1.277831367s","start":"2026-04-22T01:00:42.11276Z","end":"2026-04-22T01:00:43.390591Z","steps":["trace[182256136] 'read index received' (duration: 1.274166455s)","trace[182256136] 'applied index is now lower than readState.Index' (duration: 3.663692ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T01:00:43.390961Z","caller":"traceutil/trace.go:171","msg":"trace[909582039] transaction","detail":"{read_only:false; response_revision:3637; number_of_response:1; }","duration":"1.279886588s","start":"2026-04-22T01:00:42.110936Z","end":"2026-04-22T01:00:43.390822Z","steps":["trace[909582039] 'process raft request' (duration: 1.279031017s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:00:43.390985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278198776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-22T01:00:43.391002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.589169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T01:00:43.391057Z","caller":"traceutil/trace.go:171","msg":"trace[139544900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3638; }","duration":"1.278306309s","start":"2026-04-22T01:00:42.112736Z","end":"2026-04-22T01:00:43.391042Z","steps":["trace[139544900] 'agreement among raft nodes before linearized reading' (duration: 1.278046312s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:00:43.391077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:00:42.110923Z","time spent":"1.280089114s","remote":"127.0.0.1:46690","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-04-22T01:00:43.391113Z","caller":"traceutil/trace.go:171","msg":"trace[868184567] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:3638; }","duration":"321.811795ms","start":"2026-04-22T01:00:43.069284Z","end":"2026-04-22T01:00:43.391096Z","steps":["trace[868184567] 'agreement among raft nodes before linearized reading' (duration: 321.528387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:00:43.391132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:00:42.112715Z","time spent":"1.278402471s","remote":"127.0.0.1:46690","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-04-22T01:00:43.391186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100786342s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-22T01:00:43.391175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:00:43.069267Z","time spent":"321.897236ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-04-22T01:00:43.391069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121653826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T01:00:43.391227Z","caller":"traceutil/trace.go:171","msg":"trace[666681524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3638; }","duration":"1.100843153s","start":"2026-04-22T01:00:42.290372Z","end":"2026-04-22T01:00:43.391215Z","steps":["trace[666681524] 'agreement among raft nodes before linearized reading' (duration: 1.100727111s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:00:43.391201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039844784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T01:00:43.391272Z","caller":"traceutil/trace.go:171","msg":"trace[210364613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3638; }","duration":"1.121894021s","start":"2026-04-22T01:00:42.269363Z","end":"2026-04-22T01:00:43.391257Z","steps":["trace[210364613] 'agreement among raft nodes before linearized reading' (duration: 1.121657186s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:00:43.391321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:00:42.269345Z","time spent":"1.121963704s","remote":"127.0.0.1:46432","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-04-22T01:00:43.391357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270597966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T01:00:43.39141Z","caller":"traceutil/trace.go:171","msg":"trace[2049102198] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:3638; }","duration":"1.270646697s","start":"2026-04-22T01:00:42.120752Z","end":"2026-04-22T01:00:43.391399Z","steps":["trace[2049102198] 'agreement among raft nodes before linearized reading' (duration: 1.270547014s)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:00:43.391407Z","caller":"traceutil/trace.go:171","msg":"trace[340187801] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3638; }","duration":"1.040151191s","start":"2026-04-22T01:00:42.351154Z","end":"2026-04-22T01:00:43.391305Z","steps":["trace[340187801] 'agreement among raft nodes before linearized reading' (duration: 1.039814083s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:00:43.391453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:00:42.120705Z","time spent":"1.270735949s","remote":"127.0.0.1:46460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.144\" "} {"level":"warn","ts":"2026-04-22T01:00:43.391494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:00:42.351138Z","time spent":"1.040339896s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-22T01:00:43.39127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:00:42.290356Z","time spent":"1.100907225s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T01:00:43.391794Z","caller":"traceutil/trace.go:171","msg":"trace[369979915] transaction","detail":"{read_only:false; response_revision:3638; number_of_response:1; }","duration":"784.676379ms","start":"2026-04-22T01:00:42.606055Z","end":"2026-04-22T01:00:43.390731Z","steps":["trace[369979915] 'process raft request' (duration: 784.463104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:00:43.391922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:00:42.606036Z","time spent":"785.806758ms","remote":"127.0.0.1:46612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T01:00:43.393814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280260898s","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-04-22T01:00:43.393872Z","caller":"traceutil/trace.go:171","msg":"trace[1782203642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3638; }","duration":"1.28033769s","start":"2026-04-22T01:00:42.11352Z","end":"2026-04-22T01:00:43.393858Z","steps":["trace[1782203642] 'agreement among raft nodes before linearized reading' (duration: 1.277496899s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:00:43.393908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:00:42.113512Z","time spent":"1.280389241s","remote":"127.0.0.1:46690","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-04-22T01:01:46.778712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201763226923048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T01:01:47.278908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201763226923048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T01:01:47.509682Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.619363128s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T01:01:47.510607Z","caller":"traceutil/trace.go:171","msg":"trace[2076520213] transaction","detail":"{read_only:false; response_revision:4047; number_of_response:1; }","duration":"1.62040635s","start":"2026-04-22T01:01:45.890168Z","end":"2026-04-22T01:01:47.510575Z","steps":["trace[2076520213] 'process raft request' (duration: 1.619990081s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:01:47.510809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:01:45.890148Z","time spent":"1.620550514s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T01:01:47.51132Z","caller":"traceutil/trace.go:171","msg":"trace[694234354] transaction","detail":"{read_only:false; response_revision:4049; number_of_response:1; }","duration":"1.618983781s","start":"2026-04-22T01:01:45.892258Z","end":"2026-04-22T01:01:47.511241Z","steps":["trace[694234354] 'process raft request' (duration: 1.618893649s)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:01:47.511451Z","caller":"traceutil/trace.go:171","msg":"trace[1766978835] transaction","detail":"{read_only:false; response_revision:4050; number_of_response:1; }","duration":"1.470512207s","start":"2026-04-22T01:01:46.040923Z","end":"2026-04-22T01:01:47.511435Z","steps":["trace[1766978835] 'process raft request' (duration: 1.470275953s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:01:47.511524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:01:46.040891Z","time spent":"1.470599309s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T01:01:47.511392Z","caller":"traceutil/trace.go:171","msg":"trace[1016021257] transaction","detail":"{read_only:false; response_revision:4048; number_of_response:1; }","duration":"1.619237985s","start":"2026-04-22T01:01:45.892083Z","end":"2026-04-22T01:01:47.511321Z","steps":["trace[1016021257] 'process raft request' (duration: 1.618920999s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:01:47.511635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:01:45.892225Z","time spent":"1.619181214s","remote":"127.0.0.1:46690","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-04-22T01:01:47.511668Z","caller":"traceutil/trace.go:171","msg":"trace[49480967] transaction","detail":"{read_only:false; response_revision:4051; number_of_response:1; }","duration":"727.288342ms","start":"2026-04-22T01:01:46.784343Z","end":"2026-04-22T01:01:47.511632Z","steps":["trace[49480967] 'process raft request' (duration: 727.016366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:01:47.512011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:01:46.784125Z","time spent":"727.756523ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T01:01:47.511993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:01:45.892062Z","time spent":"1.619752867s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T01:01:47.515401Z","caller":"traceutil/trace.go:171","msg":"trace[1703284438] linearizableReadLoop","detail":"{readStateIndex:4207; appliedIndex:4201; }","duration":"1.343851506s","start":"2026-04-22T01:01:46.171527Z","end":"2026-04-22T01:01:47.515379Z","steps":["trace[1703284438] 'read index received' (duration: 1.338707437s)","trace[1703284438] 'applied index is now lower than readState.Index' (duration: 5.142289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T01:01:47.515636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.613255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T01:01:47.515671Z","caller":"traceutil/trace.go:171","msg":"trace[368205568] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:4051; }","duration":"732.719797ms","start":"2026-04-22T01:01:46.782943Z","end":"2026-04-22T01:01:47.515663Z","steps":["trace[368205568] 'agreement among raft nodes before linearized reading' (duration: 732.588335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:01:47.515603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344043509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T01:01:47.515783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.332387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T01:01:47.515784Z","caller":"traceutil/trace.go:171","msg":"trace[2076500835] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:4051; }","duration":"1.344273744s","start":"2026-04-22T01:01:46.171489Z","end":"2026-04-22T01:01:47.515763Z","steps":["trace[2076500835] 'agreement among raft nodes before linearized reading' (duration: 1.344018589s)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:01:47.51581Z","caller":"traceutil/trace.go:171","msg":"trace[905246654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4051; }","duration":"730.368718ms","start":"2026-04-22T01:01:46.785435Z","end":"2026-04-22T01:01:47.515804Z","steps":["trace[905246654] 'agreement among raft nodes before linearized reading' (duration: 730.322607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:01:47.515839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:01:46.78543Z","time spent":"730.40598ms","remote":"127.0.0.1:46432","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-04-22T01:01:47.515839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:01:46.171477Z","time spent":"1.344348815s","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-04-22T01:01:47.5158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.579177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-host-details\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T01:01:47.515929Z","caller":"traceutil/trace.go:171","msg":"trace[856213001] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-host-details; range_end:; response_count:0; response_revision:4051; }","duration":"400.754502ms","start":"2026-04-22T01:01:47.115128Z","end":"2026-04-22T01:01:47.515882Z","steps":["trace[856213001] 'agreement among raft nodes before linearized reading' (duration: 400.494705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:01:47.516021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:01:47.1151Z","time spent":"400.909244ms","remote":"127.0.0.1:46546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-host-details\" "} {"level":"warn","ts":"2026-04-22T01:01:47.515732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:01:46.782872Z","time spent":"732.85062ms","remote":"127.0.0.1:53510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":20,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-04-22T01:02:24.39537Z","caller":"traceutil/trace.go:171","msg":"trace[1756279] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4704; }","duration":"199.959898ms","start":"2026-04-22T01:02:24.195371Z","end":"2026-04-22T01:02:24.395331Z","steps":["trace[1756279] 'process raft request' (duration: 197.444108ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:27.583377Z","caller":"traceutil/trace.go:171","msg":"trace[827764777] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"122.301964ms","start":"2026-04-22T01:02:27.461039Z","end":"2026-04-22T01:02:27.583341Z","steps":["trace[827764777] 'process raft request' (duration: 121.856846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:27.893262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.913203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T01:02:27.89381Z","caller":"traceutil/trace.go:171","msg":"trace[1439073125] linearizableReadLoop","detail":"{readStateIndex:4955; appliedIndex:4954; }","duration":"302.088974ms","start":"2026-04-22T01:02:27.591689Z","end":"2026-04-22T01:02:27.893778Z","steps":["trace[1439073125] 'read index received' (duration: 49.575139ms)","trace[1439073125] 'applied index is now lower than readState.Index' (duration: 252.510865ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T01:02:27.893818Z","caller":"traceutil/trace.go:171","msg":"trace[2090038471] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"298.352999ms","start":"2026-04-22T01:02:27.595446Z","end":"2026-04-22T01:02:27.893799Z","steps":["trace[2090038471] 'process raft request' (duration: 298.193057ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:27.893762Z","caller":"traceutil/trace.go:171","msg":"trace[783964181] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"303.601354ms","start":"2026-04-22T01:02:27.590068Z","end":"2026-04-22T01:02:27.893669Z","steps":["trace[783964181] 'process raft request' (duration: 51.15584ms)","trace[783964181] 'compare' (duration: 251.22276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T01:02:27.89401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:02:27.590037Z","time spent":"303.88513ms","remote":"127.0.0.1:46504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T01:02:27.894041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.333448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-22T01:02:27.894098Z","caller":"traceutil/trace.go:171","msg":"trace[1198304558] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4773; }","duration":"302.428281ms","start":"2026-04-22T01:02:27.591655Z","end":"2026-04-22T01:02:27.894083Z","steps":["trace[1198304558] 'agreement among raft nodes before linearized reading' (duration: 302.216897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:27.894142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:02:27.591646Z","time spent":"302.488252ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":2666,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" "} {"level":"info","ts":"2026-04-22T01:02:27.898997Z","caller":"traceutil/trace.go:171","msg":"trace[1493953225] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"225.522162ms","start":"2026-04-22T01:02:27.673442Z","end":"2026-04-22T01:02:27.898964Z","steps":["trace[1493953225] 'process raft request' (duration: 225.273298ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:27.899586Z","caller":"traceutil/trace.go:171","msg":"trace[1901585503] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"202.817385ms","start":"2026-04-22T01:02:27.696669Z","end":"2026-04-22T01:02:27.899487Z","steps":["trace[1901585503] 'process raft request' (duration: 202.241573ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:27.899733Z","caller":"traceutil/trace.go:171","msg":"trace[1747318322] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"203.049869ms","start":"2026-04-22T01:02:27.696669Z","end":"2026-04-22T01:02:27.899719Z","steps":["trace[1747318322] 'process raft request' (duration: 202.639122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:27.900124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.956594ms","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-04-22T01:02:27.900177Z","caller":"traceutil/trace.go:171","msg":"trace[349315050] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:4777; }","duration":"254.107117ms","start":"2026-04-22T01:02:27.646058Z","end":"2026-04-22T01:02:27.900165Z","steps":["trace[349315050] 'agreement among raft nodes before linearized reading' (duration: 253.903773ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:27.900374Z","caller":"traceutil/trace.go:171","msg":"trace[600807087] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"203.212902ms","start":"2026-04-22T01:02:27.697151Z","end":"2026-04-22T01:02:27.900364Z","steps":["trace[600807087] 'process raft request' (duration: 202.521778ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:29.244856Z","caller":"traceutil/trace.go:171","msg":"trace[802549091] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"124.859347ms","start":"2026-04-22T01:02:29.119963Z","end":"2026-04-22T01:02:29.244822Z","steps":["trace[802549091] 'process raft request' (duration: 124.663613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:35.771703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.653073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T01:02:35.772074Z","caller":"traceutil/trace.go:171","msg":"trace[844585725] linearizableReadLoop","detail":"{readStateIndex:5100; appliedIndex:5098; }","duration":"454.438087ms","start":"2026-04-22T01:02:35.317612Z","end":"2026-04-22T01:02:35.77205Z","steps":["trace[844585725] 'read index received' (duration: 11.947673ms)","trace[844585725] 'applied index is now lower than readState.Index' (duration: 442.489064ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T01:02:35.772154Z","caller":"traceutil/trace.go:171","msg":"trace[1190805882] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"454.478127ms","start":"2026-04-22T01:02:35.317634Z","end":"2026-04-22T01:02:35.772112Z","steps":["trace[1190805882] 'process raft request' (duration: 454.338504ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:35.772577Z","caller":"traceutil/trace.go:171","msg":"trace[796768085] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"455.041179ms","start":"2026-04-22T01:02:35.317506Z","end":"2026-04-22T01:02:35.772547Z","steps":["trace[796768085] 'process raft request' (duration: 318.422256ms)","trace[796768085] 'compare' (duration: 135.44551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T01:02:35.772652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:02:35.31761Z","time spent":"454.974947ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1273,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T01:02:35.772161Z","caller":"traceutil/trace.go:171","msg":"trace[2014669955] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"454.555558ms","start":"2026-04-22T01:02:35.317568Z","end":"2026-04-22T01:02:35.772123Z","steps":["trace[2014669955] 'process raft request' (duration: 454.275253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:35.773117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:02:35.317558Z","time spent":"455.411825ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":850,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T01:02:35.772414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.783114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6c1d8549-4009-42d1-ae9f-f83a5ac9dcca\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-22T01:02:35.773259Z","caller":"traceutil/trace.go:171","msg":"trace[2013504037] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6c1d8549-4009-42d1-ae9f-f83a5ac9dcca; range_end:; response_count:1; response_revision:4915; }","duration":"455.65386ms","start":"2026-04-22T01:02:35.317582Z","end":"2026-04-22T01:02:35.773236Z","steps":["trace[2013504037] 'agreement among raft nodes before linearized reading' (duration: 454.622339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:35.773328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:02:35.317574Z","time spent":"455.734161ms","remote":"127.0.0.1:46600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1458,"request content":"key:\"/registry/persistentvolumes/pvc-6c1d8549-4009-42d1-ae9f-f83a5ac9dcca\" "} {"level":"warn","ts":"2026-04-22T01:02:35.773583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:02:35.317478Z","time spent":"455.14809ms","remote":"127.0.0.1:46706","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-04-22T01:02:36.153805Z","caller":"traceutil/trace.go:171","msg":"trace[1567616631] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"222.572953ms","start":"2026-04-22T01:02:35.9312Z","end":"2026-04-22T01:02:36.153773Z","steps":["trace[1567616631] 'process raft request' (duration: 222.317618ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:36.153972Z","caller":"traceutil/trace.go:171","msg":"trace[90092342] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"227.162863ms","start":"2026-04-22T01:02:35.926802Z","end":"2026-04-22T01:02:36.153965Z","steps":["trace[90092342] 'process raft request' (duration: 226.550501ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:36.154217Z","caller":"traceutil/trace.go:171","msg":"trace[811068387] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"206.544301ms","start":"2026-04-22T01:02:35.94765Z","end":"2026-04-22T01:02:36.154194Z","steps":["trace[811068387] 'process raft request' (duration: 206.083511ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:36.154304Z","caller":"traceutil/trace.go:171","msg":"trace[86941533] linearizableReadLoop","detail":"{readStateIndex:5107; appliedIndex:5103; }","duration":"194.557497ms","start":"2026-04-22T01:02:35.95972Z","end":"2026-04-22T01:02:36.154278Z","steps":["trace[86941533] 'read index received' (duration: 193.612479ms)","trace[86941533] 'applied index is now lower than readState.Index' (duration: 943.668µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T01:02:36.154388Z","caller":"traceutil/trace.go:171","msg":"trace[1812242311] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"205.99381ms","start":"2026-04-22T01:02:35.948386Z","end":"2026-04-22T01:02:36.154379Z","steps":["trace[1812242311] 'process raft request' (duration: 205.743525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:36.154558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.584227ms","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-04-22T01:02:36.15459Z","caller":"traceutil/trace.go:171","msg":"trace[730943232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4921; }","duration":"156.660768ms","start":"2026-04-22T01:02:35.997922Z","end":"2026-04-22T01:02:36.154583Z","steps":["trace[730943232] 'agreement among raft nodes before linearized reading' (duration: 156.542205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:36.15463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.663466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-22T01:02:36.154634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.614047ms","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-04-22T01:02:36.154667Z","caller":"traceutil/trace.go:171","msg":"trace[785996252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4921; }","duration":"139.739229ms","start":"2026-04-22T01:02:36.014919Z","end":"2026-04-22T01:02:36.154658Z","steps":["trace[785996252] 'agreement among raft nodes before linearized reading' (duration: 139.668927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:36.154684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.949279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-22T01:02:36.154708Z","caller":"traceutil/trace.go:171","msg":"trace[1766305186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4921; }","duration":"147.024241ms","start":"2026-04-22T01:02:36.007677Z","end":"2026-04-22T01:02:36.154701Z","steps":["trace[1766305186] 'agreement among raft nodes before linearized reading' (duration: 146.9625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:36.154725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.91656ms","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-04-22T01:02:36.154745Z","caller":"traceutil/trace.go:171","msg":"trace[1064301144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4921; }","duration":"164.053903ms","start":"2026-04-22T01:02:35.990685Z","end":"2026-04-22T01:02:36.154738Z","steps":["trace[1064301144] 'agreement among raft nodes before linearized reading' (duration: 163.996882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:36.154558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.760279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-65142fd9-818b-4be5-9b53-e776b42f1c78\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-22T01:02:36.154777Z","caller":"traceutil/trace.go:171","msg":"trace[391096829] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-65142fd9-818b-4be5-9b53-e776b42f1c78; range_end:; response_count:1; response_revision:4921; }","duration":"218.051915ms","start":"2026-04-22T01:02:35.936718Z","end":"2026-04-22T01:02:36.15477Z","steps":["trace[391096829] 'agreement among raft nodes before linearized reading' (duration: 217.684087ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:36.154667Z","caller":"traceutil/trace.go:171","msg":"trace[480963026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4921; }","duration":"162.713727ms","start":"2026-04-22T01:02:35.991941Z","end":"2026-04-22T01:02:36.154655Z","steps":["trace[480963026] 'agreement among raft nodes before linearized reading' (duration: 162.642445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:36.154559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.780033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-04-22T01:02:36.154893Z","caller":"traceutil/trace.go:171","msg":"trace[1122542788] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4921; }","duration":"191.148601ms","start":"2026-04-22T01:02:35.963732Z","end":"2026-04-22T01:02:36.15488Z","steps":["trace[1122542788] 'agreement among raft nodes before linearized reading' (duration: 190.704412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:36.154891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.964767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18a88826f7cb0046\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-04-22T01:02:36.154933Z","caller":"traceutil/trace.go:171","msg":"trace[1646271898] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18a88826f7cb0046; range_end:; response_count:1; response_revision:4921; }","duration":"191.048159ms","start":"2026-04-22T01:02:35.963873Z","end":"2026-04-22T01:02:36.154921Z","steps":["trace[1646271898] 'agreement among raft nodes before linearized reading' (duration: 190.938197ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:38.394835Z","caller":"traceutil/trace.go:171","msg":"trace[440669352] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"133.344136ms","start":"2026-04-22T01:02:38.261433Z","end":"2026-04-22T01:02:38.394777Z","steps":["trace[440669352] 'process raft request' (duration: 132.429798ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:38.39718Z","caller":"traceutil/trace.go:171","msg":"trace[874068779] linearizableReadLoop","detail":"{readStateIndex:5132; appliedIndex:5130; }","duration":"132.96871ms","start":"2026-04-22T01:02:38.264193Z","end":"2026-04-22T01:02:38.397161Z","steps":["trace[874068779] 'read index received' (duration: 130.198996ms)","trace[874068779] 'applied index is now lower than readState.Index' (duration: 2.768734ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T01:02:38.397223Z","caller":"traceutil/trace.go:171","msg":"trace[2107284137] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"134.477638ms","start":"2026-04-22T01:02:38.262712Z","end":"2026-04-22T01:02:38.39719Z","steps":["trace[2107284137] 'process raft request' (duration: 134.300415ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:38.397274Z","caller":"traceutil/trace.go:171","msg":"trace[2056059785] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"133.614942ms","start":"2026-04-22T01:02:38.26365Z","end":"2026-04-22T01:02:38.397265Z","steps":["trace[2056059785] 'process raft request' (duration: 133.487579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:38.397512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.306515ms","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-04-22T01:02:38.39754Z","caller":"traceutil/trace.go:171","msg":"trace[336056750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4945; }","duration":"133.367236ms","start":"2026-04-22T01:02:38.264165Z","end":"2026-04-22T01:02:38.397532Z","steps":["trace[336056750] 'agreement among raft nodes before linearized reading' (duration: 133.238834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:38.397718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.884796ms","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-04-22T01:02:38.397789Z","caller":"traceutil/trace.go:171","msg":"trace[188223746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4945; }","duration":"132.97998ms","start":"2026-04-22T01:02:38.264798Z","end":"2026-04-22T01:02:38.397778Z","steps":["trace[188223746] 'agreement among raft nodes before linearized reading' (duration: 132.748635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:52.254336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.266628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-22T01:02:52.254462Z","caller":"traceutil/trace.go:171","msg":"trace[1599455009] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:5114; }","duration":"107.451641ms","start":"2026-04-22T01:02:52.146986Z","end":"2026-04-22T01:02:52.254437Z","steps":["trace[1599455009] 'range keys from in-memory index tree' (duration: 107.139515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:52.254948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.14226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-22T01:02:52.255107Z","caller":"traceutil/trace.go:171","msg":"trace[1203822851] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5114; }","duration":"102.448676ms","start":"2026-04-22T01:02:52.152612Z","end":"2026-04-22T01:02:52.25506Z","steps":["trace[1203822851] 'range keys from in-memory index tree' (duration: 101.665992ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:52.633695Z","caller":"traceutil/trace.go:171","msg":"trace[9369521] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"149.338157ms","start":"2026-04-22T01:02:52.484327Z","end":"2026-04-22T01:02:52.633665Z","steps":["trace[9369521] 'process raft request' (duration: 110.690563ms)","trace[9369521] 'compare' (duration: 38.40398ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T01:02:52.659096Z","caller":"traceutil/trace.go:171","msg":"trace[380185034] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"173.261832ms","start":"2026-04-22T01:02:52.485809Z","end":"2026-04-22T01:02:52.659071Z","steps":["trace[380185034] 'process raft request' (duration: 173.080318ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:52.6596Z","caller":"traceutil/trace.go:171","msg":"trace[1459429251] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"173.576718ms","start":"2026-04-22T01:02:52.486009Z","end":"2026-04-22T01:02:52.659586Z","steps":["trace[1459429251] 'process raft request' (duration: 173.023037ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:02:52.65956Z","caller":"traceutil/trace.go:171","msg":"trace[171027746] linearizableReadLoop","detail":"{readStateIndex:5316; appliedIndex:5312; }","duration":"103.958796ms","start":"2026-04-22T01:02:52.555569Z","end":"2026-04-22T01:02:52.659527Z","steps":["trace[171027746] 'read index received' (duration: 39.469431ms)","trace[171027746] 'applied index is now lower than readState.Index' (duration: 64.487735ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T01:02:52.659758Z","caller":"traceutil/trace.go:171","msg":"trace[2019648475] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"172.259873ms","start":"2026-04-22T01:02:52.487489Z","end":"2026-04-22T01:02:52.659748Z","steps":["trace[2019648475] 'process raft request' (duration: 171.887646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:02:52.659895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.292701ms","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":"info","ts":"2026-04-22T01:02:52.65999Z","caller":"traceutil/trace.go:171","msg":"trace[1350724619] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5123; }","duration":"104.459435ms","start":"2026-04-22T01:02:52.555513Z","end":"2026-04-22T01:02:52.659972Z","steps":["trace[1350724619] 'agreement among raft nodes before linearized reading' (duration: 104.117378ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:03:29.045319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2846} {"level":"info","ts":"2026-04-22T01:03:29.124091Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2846,"took":"77.18829ms","hash":623190042,"current-db-size-bytes":52981760,"current-db-size":"53 MB","current-db-size-in-use-bytes":28094464,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-22T01:03:29.124179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":623190042,"revision":2846,"compact-revision":-1} {"level":"warn","ts":"2026-04-22T01:03:46.452911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.620905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T01:03:46.453033Z","caller":"traceutil/trace.go:171","msg":"trace[622335585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5546; }","duration":"181.839288ms","start":"2026-04-22T01:03:46.271166Z","end":"2026-04-22T01:03:46.453005Z","steps":["trace[622335585] 'range keys from in-memory index tree' (duration: 181.509053ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T01:03:47.185846Z","caller":"traceutil/trace.go:171","msg":"trace[321442580] transaction","detail":"{read_only:false; response_revision:5547; number_of_response:1; }","duration":"553.704898ms","start":"2026-04-22T01:03:46.632098Z","end":"2026-04-22T01:03:47.185803Z","steps":["trace[321442580] 'process raft request' (duration: 553.401552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:47.186086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:46.632066Z","time spent":"553.90292ms","remote":"127.0.0.1:46690","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-04-22T01:03:48.298704Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.113104939s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T01:03:48.299778Z","caller":"traceutil/trace.go:171","msg":"trace[1220359404] transaction","detail":"{read_only:false; response_revision:5548; number_of_response:1; }","duration":"1.664937962s","start":"2026-04-22T01:03:46.634727Z","end":"2026-04-22T01:03:48.299665Z","steps":["trace[1220359404] 'process raft request' (duration: 1.664560815s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.299994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:46.634696Z","time spent":"1.665187936s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T01:03:48.30078Z","caller":"traceutil/trace.go:171","msg":"trace[1596267307] transaction","detail":"{read_only:false; response_revision:5549; number_of_response:1; }","duration":"1.662225083s","start":"2026-04-22T01:03:46.638539Z","end":"2026-04-22T01:03:48.300764Z","steps":["trace[1596267307] 'process raft request' (duration: 1.660987872s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.300949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:46.638505Z","time spent":"1.662400238s","remote":"127.0.0.1:46690","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-04-22T01:03:48.301255Z","caller":"traceutil/trace.go:171","msg":"trace[1255584451] linearizableReadLoop","detail":"{readStateIndex:5756; appliedIndex:5752; }","duration":"1.523735017s","start":"2026-04-22T01:03:46.777508Z","end":"2026-04-22T01:03:48.301243Z","steps":["trace[1255584451] 'read index received' (duration: 408.091583ms)","trace[1255584451] 'applied index is now lower than readState.Index' (duration: 1.115641814s)"],"step_count":2} {"level":"info","ts":"2026-04-22T01:03:48.301328Z","caller":"traceutil/trace.go:171","msg":"trace[1309871298] transaction","detail":"{read_only:false; response_revision:5550; number_of_response:1; }","duration":"1.660046785s","start":"2026-04-22T01:03:46.641273Z","end":"2026-04-22T01:03:48.30132Z","steps":["trace[1309871298] 'process raft request' (duration: 1.659406893s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.301389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:46.641246Z","time spent":"1.660107956s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T01:03:48.301516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.524001403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T01:03:48.301563Z","caller":"traceutil/trace.go:171","msg":"trace[203901362] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:5550; }","duration":"1.524098774s","start":"2026-04-22T01:03:46.777454Z","end":"2026-04-22T01:03:48.301553Z","steps":["trace[203901362] 'agreement among raft nodes before linearized reading' (duration: 1.524015443s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.301598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:46.777426Z","time spent":"1.524164735s","remote":"127.0.0.1:46668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":2,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-22T01:03:48.331347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.553470187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T01:03:48.331448Z","caller":"traceutil/trace.go:171","msg":"trace[1092471072] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:5550; }","duration":"1.55365181s","start":"2026-04-22T01:03:46.777774Z","end":"2026-04-22T01:03:48.331426Z","steps":["trace[1092471072] 'agreement among raft nodes before linearized reading' (duration: 1.553482437s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.3315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:46.777744Z","time spent":"1.553742931s","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":3,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-22T01:03:48.331454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191500762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T01:03:48.331701Z","caller":"traceutil/trace.go:171","msg":"trace[132887825] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5550; }","duration":"1.191827198s","start":"2026-04-22T01:03:47.139847Z","end":"2026-04-22T01:03:48.331674Z","steps":["trace[132887825] 'agreement among raft nodes before linearized reading' (duration: 1.191447262s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.331758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:47.13982Z","time spent":"1.191924621s","remote":"127.0.0.1:46668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-04-22T01:03:48.331774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040333573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T01:03:48.331844Z","caller":"traceutil/trace.go:171","msg":"trace[342542446] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5550; }","duration":"1.040473376s","start":"2026-04-22T01:03:47.291353Z","end":"2026-04-22T01:03:48.331826Z","steps":["trace[342542446] 'agreement among raft nodes before linearized reading' (duration: 1.040340693s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.331871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319084423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-22T01:03:48.331846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.99485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T01:03:48.331883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:47.291331Z","time spent":"1.040542017s","remote":"127.0.0.1:46870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-22T01:03:48.33191Z","caller":"traceutil/trace.go:171","msg":"trace[120174226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5550; }","duration":"1.319360428s","start":"2026-04-22T01:03:47.012539Z","end":"2026-04-22T01:03:48.331899Z","steps":["trace[120174226] 'agreement among raft nodes before linearized reading' (duration: 1.319209926s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.331941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:47.0125Z","time spent":"1.319435359s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T01:03:48.331937Z","caller":"traceutil/trace.go:171","msg":"trace[328673379] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5550; }","duration":"912.109072ms","start":"2026-04-22T01:03:47.419803Z","end":"2026-04-22T01:03:48.331912Z","steps":["trace[328673379] 'agreement among raft nodes before linearized reading' (duration: 911.926589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.331934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31610912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-22T01:03:48.331997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:47.419718Z","time spent":"912.269175ms","remote":"127.0.0.1:46652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-22T01:03:48.332048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192326067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T01:03:48.332065Z","caller":"traceutil/trace.go:171","msg":"trace[1568432740] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5550; }","duration":"1.192385268s","start":"2026-04-22T01:03:47.139675Z","end":"2026-04-22T01:03:48.33206Z","steps":["trace[1568432740] 'agreement among raft nodes before linearized reading' (duration: 1.192349497s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.332092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:47.139654Z","time spent":"1.192433008s","remote":"127.0.0.1:46668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-04-22T01:03:48.332089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046004833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T01:03:48.332123Z","caller":"traceutil/trace.go:171","msg":"trace[1280379295] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5550; }","duration":"1.046096215s","start":"2026-04-22T01:03:47.286017Z","end":"2026-04-22T01:03:48.332114Z","steps":["trace[1280379295] 'agreement among raft nodes before linearized reading' (duration: 1.046030824s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.332133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347670372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-22T01:03:48.332152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:47.28598Z","time spent":"1.046164246s","remote":"127.0.0.1:46818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-04-22T01:03:48.332184Z","caller":"traceutil/trace.go:171","msg":"trace[872674760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5550; }","duration":"1.347768414s","start":"2026-04-22T01:03:46.9844Z","end":"2026-04-22T01:03:48.332168Z","steps":["trace[872674760] 'agreement among raft nodes before linearized reading' (duration: 1.347596351s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.33222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:46.984375Z","time spent":"1.347837735s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T01:03:48.332004Z","caller":"traceutil/trace.go:171","msg":"trace[1781881786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5550; }","duration":"1.316251862s","start":"2026-04-22T01:03:47.015737Z","end":"2026-04-22T01:03:48.331989Z","steps":["trace[1781881786] 'agreement among raft nodes before linearized reading' (duration: 1.315989538s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.332305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:47.015708Z","time spent":"1.316582608s","remote":"127.0.0.1:46690","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-04-22T01:03:48.332323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315992697s","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-04-22T01:03:48.332345Z","caller":"traceutil/trace.go:171","msg":"trace[1623617143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5550; }","duration":"1.316022698s","start":"2026-04-22T01:03:47.016316Z","end":"2026-04-22T01:03:48.332339Z","steps":["trace[1623617143] 'agreement among raft nodes before linearized reading' (duration: 1.315944056s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.332378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:47.016309Z","time spent":"1.316064158s","remote":"127.0.0.1:46690","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-04-22T01:03:48.332452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316433356s","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-04-22T01:03:48.332498Z","caller":"traceutil/trace.go:171","msg":"trace[1755430134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5550; }","duration":"1.316502557s","start":"2026-04-22T01:03:47.015983Z","end":"2026-04-22T01:03:48.332486Z","steps":["trace[1755430134] 'agreement among raft nodes before linearized reading' (duration: 1.316373324s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T01:03:48.332529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T01:03:47.015972Z","time spent":"1.316550639s","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T01:03:54.18265Z","caller":"traceutil/trace.go:171","msg":"trace[1864257920] transaction","detail":"{read_only:false; response_revision:5602; number_of_response:1; }","duration":"107.814165ms","start":"2026-04-22T01:03:54.074802Z","end":"2026-04-22T01:03:54.182616Z","steps":["trace[1864257920] 'process raft request' (duration: 71.458383ms)","trace[1864257920] 'compare' (duration: 36.2256ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T01:07:05.613044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.780931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T01:07:05.613534Z","caller":"traceutil/trace.go:171","msg":"trace[553820051] transaction","detail":"{read_only:false; response_revision:6918; number_of_response:1; }","duration":"155.511132ms","start":"2026-04-22T01:07:05.457992Z","end":"2026-04-22T01:07:05.613503Z","steps":["trace[553820051] 'process raft request' (duration: 28.135851ms)","trace[553820051] 'compare' (duration: 126.527608ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T01:08:29.052008Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5427} {"level":"info","ts":"2026-04-22T01:08:29.127744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5427,"took":"74.496294ms","hash":1283474790,"current-db-size-bytes":52981760,"current-db-size":"53 MB","current-db-size-in-use-bytes":18456576,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-22T01:08:29.127803Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1283474790,"revision":5427,"compact-revision":2846}