{"level":"warn","ts":"2026-05-05T13:45:46.438029Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T13:45:46.438114Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.241: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.19.213.241:2380","--initial-cluster=instance=https://199.19.213.241:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.241:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.241:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T13:45:46.438176Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T13:45:46.438191Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.241:2380"]} {"level":"info","ts":"2026-05-05T13:45:46.438206Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T13:45:46.438825Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.241:2379"]} {"level":"info","ts":"2026-05-05T13:45:46.43891Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.241:2380"],"listen-peer-urls":["https://199.19.213.241:2380"],"advertise-client-urls":["https://199.19.213.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.241:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T13:45:46.442766Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.665132ms"} {"level":"info","ts":"2026-05-05T13:45:46.449012Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"564d150b9adc490e","cluster-id":"600c20a83a9eb5a7"} {"level":"info","ts":"2026-05-05T13:45:46.449093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"564d150b9adc490e switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T13:45:46.449117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"564d150b9adc490e became follower at term 0"} {"level":"info","ts":"2026-05-05T13:45:46.449128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 564d150b9adc490e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T13:45:46.449143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"564d150b9adc490e became follower at term 1"} {"level":"info","ts":"2026-05-05T13:45:46.449183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"564d150b9adc490e switched to configuration voters=(6218649800055474446)"} {"level":"warn","ts":"2026-05-05T13:45:46.455163Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T13:45:46.457079Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T13:45:46.458269Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T13:45:46.460648Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"564d150b9adc490e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T13:45:46.46086Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"564d150b9adc490e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T13:45:46.460899Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T13:45:46.461048Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T13:45:46.461059Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T13:45:46.463438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"564d150b9adc490e switched to configuration voters=(6218649800055474446)"} {"level":"info","ts":"2026-05-05T13:45:46.463857Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"600c20a83a9eb5a7","local-member-id":"564d150b9adc490e","added-peer-id":"564d150b9adc490e","added-peer-peer-urls":["https://199.19.213.241:2380"]} {"level":"info","ts":"2026-05-05T13:45:46.465593Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T13:45:46.46581Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"564d150b9adc490e","initial-advertise-peer-urls":["https://199.19.213.241:2380"],"listen-peer-urls":["https://199.19.213.241:2380"],"advertise-client-urls":["https://199.19.213.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T13:45:46.465851Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T13:45:46.466378Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.241:2380"} {"level":"info","ts":"2026-05-05T13:45:46.466426Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.241:2380"} {"level":"info","ts":"2026-05-05T13:45:46.650431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"564d150b9adc490e is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T13:45:46.650513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"564d150b9adc490e became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T13:45:46.650542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"564d150b9adc490e received MsgPreVoteResp from 564d150b9adc490e at term 1"} {"level":"info","ts":"2026-05-05T13:45:46.650562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"564d150b9adc490e became candidate at term 2"} {"level":"info","ts":"2026-05-05T13:45:46.650574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"564d150b9adc490e received MsgVoteResp from 564d150b9adc490e at term 2"} {"level":"info","ts":"2026-05-05T13:45:46.65059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"564d150b9adc490e became leader at term 2"} {"level":"info","ts":"2026-05-05T13:45:46.65061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 564d150b9adc490e elected leader 564d150b9adc490e at term 2"} {"level":"info","ts":"2026-05-05T13:45:46.652982Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:45:46.653712Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"564d150b9adc490e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.241:2379]}","request-path":"/0/members/564d150b9adc490e/attributes","cluster-id":"600c20a83a9eb5a7","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T13:45:46.653892Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T13:45:46.653948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T13:45:46.654284Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"600c20a83a9eb5a7","local-member-id":"564d150b9adc490e","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:45:46.65442Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:45:46.654468Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T13:45:46.657378Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.241:2379"} {"level":"info","ts":"2026-05-05T13:45:46.657681Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T13:45:46.657886Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T13:45:46.658241Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T13:46:17.358673Z","caller":"traceutil/trace.go:171","msg":"trace[1901086242] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"124.686538ms","start":"2026-05-05T13:46:17.233941Z","end":"2026-05-05T13:46:17.358627Z","steps":["trace[1901086242] 'process raft request' (duration: 124.200184ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.358747Z","caller":"traceutil/trace.go:171","msg":"trace[47126402] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"125.616514ms","start":"2026-05-05T13:46:17.23306Z","end":"2026-05-05T13:46:17.358677Z","steps":["trace[47126402] 'process raft request' (duration: 124.791921ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.358939Z","caller":"traceutil/trace.go:171","msg":"trace[121747037] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"125.196642ms","start":"2026-05-05T13:46:17.233685Z","end":"2026-05-05T13:46:17.358882Z","steps":["trace[121747037] 'process raft request' (duration: 124.373259ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.359018Z","caller":"traceutil/trace.go:171","msg":"trace[254394916] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"117.873195ms","start":"2026-05-05T13:46:17.241136Z","end":"2026-05-05T13:46:17.359009Z","steps":["trace[254394916] 'process raft request' (duration: 117.525946ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.359043Z","caller":"traceutil/trace.go:171","msg":"trace[146777382] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"115.342434ms","start":"2026-05-05T13:46:17.243685Z","end":"2026-05-05T13:46:17.359027Z","steps":["trace[146777382] 'process raft request' (duration: 115.028665ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.359012Z","caller":"traceutil/trace.go:171","msg":"trace[1555514195] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"117.826775ms","start":"2026-05-05T13:46:17.241116Z","end":"2026-05-05T13:46:17.358943Z","steps":["trace[1555514195] 'process raft request' (duration: 117.511536ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.358946Z","caller":"traceutil/trace.go:171","msg":"trace[554569715] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"112.872124ms","start":"2026-05-05T13:46:17.246046Z","end":"2026-05-05T13:46:17.358918Z","steps":["trace[554569715] 'process raft request' (duration: 112.785911ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.359035Z","caller":"traceutil/trace.go:171","msg":"trace[752458392] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"119.622554ms","start":"2026-05-05T13:46:17.239383Z","end":"2026-05-05T13:46:17.359005Z","steps":["trace[752458392] 'process raft request' (duration: 119.144821ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.359197Z","caller":"traceutil/trace.go:171","msg":"trace[925722167] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"118.76517ms","start":"2026-05-05T13:46:17.240419Z","end":"2026-05-05T13:46:17.359184Z","steps":["trace[925722167] 'process raft request' (duration: 118.156323ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.35924Z","caller":"traceutil/trace.go:171","msg":"trace[1039865325] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"118.752061ms","start":"2026-05-05T13:46:17.240441Z","end":"2026-05-05T13:46:17.359193Z","steps":["trace[1039865325] 'process raft request' (duration: 118.161854ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.359276Z","caller":"traceutil/trace.go:171","msg":"trace[880421536] transaction","detail":"{read_only:false; number_of_response:1; response_revision:485; }","duration":"114.229522ms","start":"2026-05-05T13:46:17.244977Z","end":"2026-05-05T13:46:17.359206Z","steps":["trace[880421536] 'process raft request' (duration: 113.776379ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.401322Z","caller":"traceutil/trace.go:171","msg":"trace[1406394067] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"144.699692ms","start":"2026-05-05T13:46:17.256588Z","end":"2026-05-05T13:46:17.401288Z","steps":["trace[1406394067] 'process raft request' (duration: 144.224859ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.401332Z","caller":"traceutil/trace.go:171","msg":"trace[789258740] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"133.742223ms","start":"2026-05-05T13:46:17.26756Z","end":"2026-05-05T13:46:17.401303Z","steps":["trace[789258740] 'process raft request' (duration: 133.624169ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.401403Z","caller":"traceutil/trace.go:171","msg":"trace[1711576789] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"136.055408ms","start":"2026-05-05T13:46:17.265312Z","end":"2026-05-05T13:46:17.401367Z","steps":["trace[1711576789] 'process raft request' (duration: 135.796921ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:17.401319Z","caller":"traceutil/trace.go:171","msg":"trace[1125159467] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"139.710981ms","start":"2026-05-05T13:46:17.261585Z","end":"2026-05-05T13:46:17.401296Z","steps":["trace[1125159467] 'process raft request' (duration: 139.409763ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:23.520631Z","caller":"traceutil/trace.go:171","msg":"trace[2128797756] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"144.439795ms","start":"2026-05-05T13:46:23.376158Z","end":"2026-05-05T13:46:23.520598Z","steps":["trace[2128797756] 'process raft request' (duration: 143.673093ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:46:23.694379Z","caller":"traceutil/trace.go:171","msg":"trace[1060666893] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"107.309847ms","start":"2026-05-05T13:46:23.587002Z","end":"2026-05-05T13:46:23.694312Z","steps":["trace[1060666893] 'process raft request' (duration: 63.399309ms)","trace[1060666893] 'compare' (duration: 43.491217ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:46:24.264319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.777004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:46:24.264444Z","caller":"traceutil/trace.go:171","msg":"trace[1040326780] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"556.678832ms","start":"2026-05-05T13:46:23.707749Z","end":"2026-05-05T13:46:24.264428Z","steps":["trace[1040326780] 'read index received' (duration: 153.878979ms)","trace[1040326780] 'applied index is now lower than readState.Index' (duration: 402.798373ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:46:24.264511Z","caller":"traceutil/trace.go:171","msg":"trace[1947314023] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"564.243926ms","start":"2026-05-05T13:46:23.700223Z","end":"2026-05-05T13:46:24.264467Z","steps":["trace[1947314023] 'process raft request' (duration: 161.513376ms)","trace[1947314023] 'compare' (duration: 401.626859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:46:24.264677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:46:23.700104Z","time spent":"564.484482ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:46:24.264951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.145126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:46:24.265091Z","caller":"traceutil/trace.go:171","msg":"trace[2064610236] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:581; }","duration":"557.379702ms","start":"2026-05-05T13:46:23.707674Z","end":"2026-05-05T13:46:24.265053Z","steps":["trace[2064610236] 'agreement among raft nodes before linearized reading' (duration: 557.108065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:46:24.265012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.323966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T13:46:24.265157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:46:23.70765Z","time spent":"557.494745ms","remote":"127.0.0.1:34120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-05T13:46:24.265154Z","caller":"traceutil/trace.go:171","msg":"trace[442869145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"158.513832ms","start":"2026-05-05T13:46:24.106624Z","end":"2026-05-05T13:46:24.265138Z","steps":["trace[442869145] 'agreement among raft nodes before linearized reading' (duration: 158.271065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:46:24.265151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.904844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wh4wc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T13:46:24.265375Z","caller":"traceutil/trace.go:171","msg":"trace[1260836304] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wh4wc; range_end:; response_count:1; response_revision:581; }","duration":"504.162951ms","start":"2026-05-05T13:46:23.76116Z","end":"2026-05-05T13:46:24.265323Z","steps":["trace[1260836304] 'agreement among raft nodes before linearized reading' (duration: 503.707588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:46:24.265454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:46:23.76114Z","time spent":"504.297274ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-wh4wc\" "} {"level":"warn","ts":"2026-05-05T13:46:39.818044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.288743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T13:46:39.818148Z","caller":"traceutil/trace.go:171","msg":"trace[1059183964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"176.468869ms","start":"2026-05-05T13:46:39.641658Z","end":"2026-05-05T13:46:39.818127Z","steps":["trace[1059183964] 'range keys from in-memory index tree' (duration: 176.023946ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:29.343811Z","caller":"traceutil/trace.go:171","msg":"trace[96589859] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"125.417869ms","start":"2026-05-05T13:47:29.218358Z","end":"2026-05-05T13:47:29.343776Z","steps":["trace[96589859] 'process raft request' (duration: 125.223333ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:47:55.041816Z","caller":"traceutil/trace.go:171","msg":"trace[1085837802] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1885; }","duration":"251.910879ms","start":"2026-05-05T13:47:54.789874Z","end":"2026-05-05T13:47:55.041785Z","steps":["trace[1085837802] 'read index received' (duration: 251.737094ms)","trace[1085837802] 'applied index is now lower than readState.Index' (duration: 172.625µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:47:55.041975Z","caller":"traceutil/trace.go:171","msg":"trace[815560541] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"273.932748ms","start":"2026-05-05T13:47:54.767986Z","end":"2026-05-05T13:47:55.041918Z","steps":["trace[815560541] 'process raft request' (duration: 273.62742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:55.042113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.966205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T13:47:55.042154Z","caller":"traceutil/trace.go:171","msg":"trace[585026965] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1816; }","duration":"185.082348ms","start":"2026-05-05T13:47:54.857062Z","end":"2026-05-05T13:47:55.042144Z","steps":["trace[585026965] 'agreement among raft nodes before linearized reading' (duration: 184.881012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:55.042146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.255909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T13:47:55.042269Z","caller":"traceutil/trace.go:171","msg":"trace[2072304472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1816; }","duration":"252.453384ms","start":"2026-05-05T13:47:54.789793Z","end":"2026-05-05T13:47:55.042246Z","steps":["trace[2072304472] 'agreement among raft nodes before linearized reading' (duration: 252.135335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:55.042475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.645189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T13:47:55.042601Z","caller":"traceutil/trace.go:171","msg":"trace[283451001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1816; }","duration":"150.905387ms","start":"2026-05-05T13:47:54.891669Z","end":"2026-05-05T13:47:55.042575Z","steps":["trace[283451001] 'agreement among raft nodes before linearized reading' (duration: 150.434774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:47:55.042737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.945172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T13:47:55.042899Z","caller":"traceutil/trace.go:171","msg":"trace[479207497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1816; }","duration":"154.188568ms","start":"2026-05-05T13:47:54.888653Z","end":"2026-05-05T13:47:55.042842Z","steps":["trace[479207497] 'agreement among raft nodes before linearized reading' (duration: 153.494569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:46.365076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.39591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:48:46.365214Z","caller":"traceutil/trace.go:171","msg":"trace[170606368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2144; }","duration":"148.640456ms","start":"2026-05-05T13:48:46.216544Z","end":"2026-05-05T13:48:46.365185Z","steps":["trace[170606368] 'range keys from in-memory index tree' (duration: 148.183983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:46.365442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.230454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-05T13:48:46.365596Z","caller":"traceutil/trace.go:171","msg":"trace[531018781] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2144; }","duration":"270.464771ms","start":"2026-05-05T13:48:46.095098Z","end":"2026-05-05T13:48:46.365563Z","steps":["trace[531018781] 'range keys from in-memory index tree' (duration: 269.952617ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:46.493494Z","caller":"traceutil/trace.go:171","msg":"trace[2032954047] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"174.302915ms","start":"2026-05-05T13:48:46.319149Z","end":"2026-05-05T13:48:46.493452Z","steps":["trace[2032954047] 'process raft request' (duration: 174.056518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:46.748923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.087527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:48:46.749171Z","caller":"traceutil/trace.go:171","msg":"trace[809054798] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2227; }","duration":"223.544796ms","start":"2026-05-05T13:48:46.525608Z","end":"2026-05-05T13:48:46.749153Z","steps":["trace[809054798] 'read index received' (duration: 42.12856ms)","trace[809054798] 'applied index is now lower than readState.Index' (duration: 181.415066ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:48:46.749276Z","caller":"traceutil/trace.go:171","msg":"trace[627806371] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"253.28621ms","start":"2026-05-05T13:48:46.49598Z","end":"2026-05-05T13:48:46.749266Z","steps":["trace[627806371] 'process raft request' (duration: 253.077844ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:46.749432Z","caller":"traceutil/trace.go:171","msg":"trace[1225649003] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"213.077093ms","start":"2026-05-05T13:48:46.536342Z","end":"2026-05-05T13:48:46.749419Z","steps":["trace[1225649003] 'process raft request' (duration: 212.774045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:46.749637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.03069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T13:48:46.74969Z","caller":"traceutil/trace.go:171","msg":"trace[71368258] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2148; }","duration":"224.132252ms","start":"2026-05-05T13:48:46.525542Z","end":"2026-05-05T13:48:46.749674Z","steps":["trace[71368258] 'agreement among raft nodes before linearized reading' (duration: 223.972338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:48:46.749869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.493578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T13:48:46.750022Z","caller":"traceutil/trace.go:171","msg":"trace[274877740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2148; }","duration":"155.709915ms","start":"2026-05-05T13:48:46.59428Z","end":"2026-05-05T13:48:46.74999Z","steps":["trace[274877740] 'agreement among raft nodes before linearized reading' (duration: 155.361535ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:48:46.75054Z","caller":"traceutil/trace.go:171","msg":"trace[55036149] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"379.116707ms","start":"2026-05-05T13:48:46.371374Z","end":"2026-05-05T13:48:46.75049Z","steps":["trace[55036149] 'process raft request' (duration: 196.376175ms)","trace[55036149] 'compare' (duration: 180.938802ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:48:46.750712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:48:46.371342Z","time spent":"379.260961ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:50:12.152414Z","caller":"traceutil/trace.go:171","msg":"trace[1055565020] linearizableReadLoop","detail":"{readStateIndex:2910; appliedIndex:2909; }","duration":"200.707225ms","start":"2026-05-05T13:50:11.951672Z","end":"2026-05-05T13:50:12.15238Z","steps":["trace[1055565020] 'read index received' (duration: 168.991786ms)","trace[1055565020] 'applied index is now lower than readState.Index' (duration: 31.713969ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:50:12.152506Z","caller":"traceutil/trace.go:171","msg":"trace[1333397893] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"271.973321ms","start":"2026-05-05T13:50:11.880507Z","end":"2026-05-05T13:50:12.15248Z","steps":["trace[1333397893] 'process raft request' (duration: 240.157439ms)","trace[1333397893] 'compare' (duration: 31.428271ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:50:12.152704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.013073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-05T13:50:12.152895Z","caller":"traceutil/trace.go:171","msg":"trace[262308228] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2802; }","duration":"201.24306ms","start":"2026-05-05T13:50:11.95162Z","end":"2026-05-05T13:50:12.152863Z","steps":["trace[262308228] 'agreement among raft nodes before linearized reading' (duration: 200.866839ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:08.743753Z","caller":"traceutil/trace.go:171","msg":"trace[1206473243] transaction","detail":"{read_only:false; response_revision:3107; number_of_response:1; }","duration":"513.273442ms","start":"2026-05-05T13:51:08.230415Z","end":"2026-05-05T13:51:08.743688Z","steps":["trace[1206473243] 'process raft request' (duration: 512.83624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:08.744193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.443937ms","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-05-05T13:51:08.744299Z","caller":"traceutil/trace.go:171","msg":"trace[927729927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3107; }","duration":"259.624843ms","start":"2026-05-05T13:51:08.484652Z","end":"2026-05-05T13:51:08.744277Z","steps":["trace[927729927] 'agreement among raft nodes before linearized reading' (duration: 259.306194ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:08.744281Z","caller":"traceutil/trace.go:171","msg":"trace[1951735912] linearizableReadLoop","detail":"{readStateIndex:3227; appliedIndex:3226; }","duration":"259.488099ms","start":"2026-05-05T13:51:08.484721Z","end":"2026-05-05T13:51:08.744209Z","steps":["trace[1951735912] 'read index received' (duration: 258.621805ms)","trace[1951735912] 'applied index is now lower than readState.Index' (duration: 269.118µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:51:08.744768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:08.230401Z","time spent":"513.517118ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:51:08.748824Z","caller":"traceutil/trace.go:171","msg":"trace[575665736] transaction","detail":"{read_only:false; response_revision:3108; number_of_response:1; }","duration":"161.434474ms","start":"2026-05-05T13:51:08.587371Z","end":"2026-05-05T13:51:08.748806Z","steps":["trace[575665736] 'process raft request' (duration: 161.146956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:08.748949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.540841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T13:51:08.749065Z","caller":"traceutil/trace.go:171","msg":"trace[759128540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3108; }","duration":"180.723547ms","start":"2026-05-05T13:51:08.568316Z","end":"2026-05-05T13:51:08.74904Z","steps":["trace[759128540] 'agreement among raft nodes before linearized reading' (duration: 180.409418ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:11.228765Z","caller":"traceutil/trace.go:171","msg":"trace[561386631] transaction","detail":"{read_only:false; response_revision:3119; number_of_response:1; }","duration":"475.282904ms","start":"2026-05-05T13:51:10.753455Z","end":"2026-05-05T13:51:11.228738Z","steps":["trace[561386631] 'process raft request' (duration: 475.13585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:11.22891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:10.753425Z","time spent":"475.406678ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:51:11.553805Z","caller":"traceutil/trace.go:171","msg":"trace[1455009382] transaction","detail":"{read_only:false; response_revision:3120; number_of_response:1; }","duration":"800.265572ms","start":"2026-05-05T13:51:10.753512Z","end":"2026-05-05T13:51:11.553777Z","steps":["trace[1455009382] 'process raft request' (duration: 798.963666ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:11.553831Z","caller":"traceutil/trace.go:171","msg":"trace[1824668309] transaction","detail":"{read_only:false; response_revision:3123; number_of_response:1; }","duration":"255.8649ms","start":"2026-05-05T13:51:11.297946Z","end":"2026-05-05T13:51:11.553811Z","steps":["trace[1824668309] 'process raft request' (duration: 255.834549ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:11.553956Z","caller":"traceutil/trace.go:171","msg":"trace[697901231] transaction","detail":"{read_only:false; response_revision:3121; number_of_response:1; }","duration":"511.011501ms","start":"2026-05-05T13:51:11.042937Z","end":"2026-05-05T13:51:11.553949Z","steps":["trace[697901231] 'process raft request' (duration: 510.727873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:11.553955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:10.753489Z","time spent":"800.386344ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:51:11.554029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:11.042914Z","time spent":"511.056291ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:51:11.554075Z","caller":"traceutil/trace.go:171","msg":"trace[114464256] linearizableReadLoop","detail":"{readStateIndex:3241; appliedIndex:3240; }","duration":"799.774548ms","start":"2026-05-05T13:51:10.754291Z","end":"2026-05-05T13:51:11.554065Z","steps":["trace[114464256] 'read index received' (duration: 474.480492ms)","trace[114464256] 'applied index is now lower than readState.Index' (duration: 325.292466ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:51:11.554089Z","caller":"traceutil/trace.go:171","msg":"trace[1562111167] transaction","detail":"{read_only:false; response_revision:3122; number_of_response:1; }","duration":"261.513926ms","start":"2026-05-05T13:51:11.292568Z","end":"2026-05-05T13:51:11.554082Z","steps":["trace[1562111167] 'process raft request' (duration: 261.185377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:11.554205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.915682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-05T13:51:11.554222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.919304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T13:51:11.554229Z","caller":"traceutil/trace.go:171","msg":"trace[264599618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3123; }","duration":"799.940253ms","start":"2026-05-05T13:51:10.754281Z","end":"2026-05-05T13:51:11.554221Z","steps":["trace[264599618] 'agreement among raft nodes before linearized reading' (duration: 799.84365ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:51:11.554245Z","caller":"traceutil/trace.go:171","msg":"trace[572518721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3123; }","duration":"794.967896ms","start":"2026-05-05T13:51:10.75927Z","end":"2026-05-05T13:51:11.554238Z","steps":["trace[572518721] 'agreement among raft nodes before linearized reading' (duration: 794.881213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:11.554256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:10.754227Z","time spent":"800.024025ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T13:51:11.554262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:10.759259Z","time spent":"794.999386ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T13:51:11.554344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.039546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-05T13:51:11.554418Z","caller":"traceutil/trace.go:171","msg":"trace[1241639770] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3123; }","duration":"671.158669ms","start":"2026-05-05T13:51:10.883239Z","end":"2026-05-05T13:51:11.554398Z","steps":["trace[1241639770] 'agreement among raft nodes before linearized reading' (duration: 670.942513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:51:11.554455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:51:10.883218Z","time spent":"671.228731ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-05T13:54:16.795044Z","caller":"traceutil/trace.go:171","msg":"trace[2057111107] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"132.335864ms","start":"2026-05-05T13:54:16.662674Z","end":"2026-05-05T13:54:16.79501Z","steps":["trace[2057111107] 'process raft request' (duration: 132.032495ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:16.795327Z","caller":"traceutil/trace.go:171","msg":"trace[1945774279] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"132.404716ms","start":"2026-05-05T13:54:16.662723Z","end":"2026-05-05T13:54:16.795128Z","steps":["trace[1945774279] 'process raft request' (duration: 132.217691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:18.916623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.553991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T13:54:18.916708Z","caller":"traceutil/trace.go:171","msg":"trace[1233332258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5014; }","duration":"116.716575ms","start":"2026-05-05T13:54:18.79997Z","end":"2026-05-05T13:54:18.916687Z","steps":["trace[1233332258] 'range keys from in-memory index tree' (duration: 116.308384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:18.916834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.89336ms","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-05-05T13:54:18.916905Z","caller":"traceutil/trace.go:171","msg":"trace[1028433826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5014; }","duration":"117.006733ms","start":"2026-05-05T13:54:18.799881Z","end":"2026-05-05T13:54:18.916888Z","steps":["trace[1028433826] 'range keys from in-memory index tree' (duration: 116.740986ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:19.051914Z","caller":"traceutil/trace.go:171","msg":"trace[941627433] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"128.061928ms","start":"2026-05-05T13:54:18.92382Z","end":"2026-05-05T13:54:19.051882Z","steps":["trace[941627433] 'process raft request' (duration: 127.77042ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:19.065339Z","caller":"traceutil/trace.go:171","msg":"trace[583182074] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"140.789337ms","start":"2026-05-05T13:54:18.924507Z","end":"2026-05-05T13:54:19.065296Z","steps":["trace[583182074] 'process raft request' (duration: 140.53454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:31.444402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.153468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:54:31.444529Z","caller":"traceutil/trace.go:171","msg":"trace[980630886] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"236.903066ms","start":"2026-05-05T13:54:31.207601Z","end":"2026-05-05T13:54:31.444504Z","steps":["trace[980630886] 'process raft request' (duration: 68.557653ms)","trace[980630886] 'compare' (duration: 168.020734ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:31.934727Z","caller":"traceutil/trace.go:171","msg":"trace[1252935027] linearizableReadLoop","detail":"{readStateIndex:5456; appliedIndex:5455; }","duration":"392.935821ms","start":"2026-05-05T13:54:31.541761Z","end":"2026-05-05T13:54:31.934696Z","steps":["trace[1252935027] 'read index received' (duration: 321.979463ms)","trace[1252935027] 'applied index is now lower than readState.Index' (duration: 70.955338ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:31.934805Z","caller":"traceutil/trace.go:171","msg":"trace[763046657] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"433.399842ms","start":"2026-05-05T13:54:31.501364Z","end":"2026-05-05T13:54:31.934764Z","steps":["trace[763046657] 'process raft request' (duration: 362.448474ms)","trace[763046657] 'compare' (duration: 70.760793ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:54:31.934984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:31.501351Z","time spent":"433.539266ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5961,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:54:31.935029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.24554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j7zcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T13:54:31.935085Z","caller":"traceutil/trace.go:171","msg":"trace[1342709171] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j7zcz; range_end:; response_count:1; response_revision:5256; }","duration":"393.362423ms","start":"2026-05-05T13:54:31.541706Z","end":"2026-05-05T13:54:31.935068Z","steps":["trace[1342709171] 'agreement among raft nodes before linearized reading' (duration: 393.165357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:31.935148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:31.541674Z","time spent":"393.460915ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-j7zcz\" "} {"level":"warn","ts":"2026-05-05T13:54:31.935129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.260569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-26smw.18acafcef46b4b8a\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-05-05T13:54:31.935223Z","caller":"traceutil/trace.go:171","msg":"trace[1464075169] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-26smw.18acafcef46b4b8a; range_end:; response_count:1; response_revision:5256; }","duration":"279.407663ms","start":"2026-05-05T13:54:31.655793Z","end":"2026-05-05T13:54:31.935201Z","steps":["trace[1464075169] 'agreement among raft nodes before linearized reading' (duration: 279.166177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:31.935048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.792725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:54:31.935356Z","caller":"traceutil/trace.go:171","msg":"trace[1963997781] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:5256; }","duration":"366.086654ms","start":"2026-05-05T13:54:31.569204Z","end":"2026-05-05T13:54:31.935291Z","steps":["trace[1963997781] 'agreement among raft nodes before linearized reading' (duration: 365.719764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:31.935461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:31.56919Z","time spent":"366.248578ms","remote":"127.0.0.1:47956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-05-05T13:54:32.138179Z","caller":"traceutil/trace.go:171","msg":"trace[258337925] linearizableReadLoop","detail":"{readStateIndex:5457; appliedIndex:5456; }","duration":"198.419289ms","start":"2026-05-05T13:54:31.939734Z","end":"2026-05-05T13:54:32.138154Z","steps":["trace[258337925] 'read index received' (duration: 112.505169ms)","trace[258337925] 'applied index is now lower than readState.Index' (duration: 85.91299ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:32.138245Z","caller":"traceutil/trace.go:171","msg":"trace[561119670] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"200.504146ms","start":"2026-05-05T13:54:31.937704Z","end":"2026-05-05T13:54:32.138209Z","steps":["trace[561119670] 'process raft request' (duration: 114.514475ms)","trace[561119670] 'compare' (duration: 85.747925ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:54:32.138412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.665135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-8hv98\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-05-05T13:54:32.138626Z","caller":"traceutil/trace.go:171","msg":"trace[1680733993] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-8hv98; range_end:; response_count:1; response_revision:5257; }","duration":"198.939134ms","start":"2026-05-05T13:54:31.939675Z","end":"2026-05-05T13:54:32.138614Z","steps":["trace[1680733993] 'agreement among raft nodes before linearized reading' (duration: 198.591204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:32.464028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.861437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:54:32.464349Z","caller":"traceutil/trace.go:171","msg":"trace[1055516013] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"523.882756ms","start":"2026-05-05T13:54:31.940412Z","end":"2026-05-05T13:54:32.464295Z","steps":["trace[1055516013] 'process raft request' (duration: 299.226977ms)","trace[1055516013] 'compare' (duration: 223.689933ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:32.464541Z","caller":"traceutil/trace.go:171","msg":"trace[611059490] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"521.885982ms","start":"2026-05-05T13:54:31.942624Z","end":"2026-05-05T13:54:32.46451Z","steps":["trace[611059490] 'process raft request' (duration: 521.577234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:32.46463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:31.940381Z","time spent":"524.066682ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1045,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:54:32.464673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:31.942596Z","time spent":"522.021156ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1563,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:32.467197Z","caller":"traceutil/trace.go:171","msg":"trace[1716701635] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"522.794768ms","start":"2026-05-05T13:54:31.944371Z","end":"2026-05-05T13:54:32.467165Z","steps":["trace[1716701635] 'process raft request' (duration: 522.439548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:32.467365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:31.944356Z","time spent":"522.924391ms","remote":"127.0.0.1:34388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5085,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:32.467874Z","caller":"traceutil/trace.go:171","msg":"trace[1560255347] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"507.662502ms","start":"2026-05-05T13:54:31.960191Z","end":"2026-05-05T13:54:32.467853Z","steps":["trace[1560255347] 'process raft request' (duration: 506.906222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:32.467963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:31.960171Z","time spent":"507.747264ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:32.46819Z","caller":"traceutil/trace.go:171","msg":"trace[1301182773] linearizableReadLoop","detail":"{readStateIndex:5461; appliedIndex:5457; }","duration":"329.680054ms","start":"2026-05-05T13:54:32.138481Z","end":"2026-05-05T13:54:32.46809Z","steps":["trace[1301182773] 'read index received' (duration: 101.171858ms)","trace[1301182773] 'applied index is now lower than readState.Index' (duration: 228.433904ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:54:32.468387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.169025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-05-05T13:54:32.468451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.005095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70788"} {"level":"info","ts":"2026-05-05T13:54:32.468528Z","caller":"traceutil/trace.go:171","msg":"trace[1783670529] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5261; }","duration":"526.093167ms","start":"2026-05-05T13:54:31.942416Z","end":"2026-05-05T13:54:32.468509Z","steps":["trace[1783670529] 'agreement among raft nodes before linearized reading' (duration: 525.81659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:32.468586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:31.94241Z","time spent":"526.16171ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":8,"response size":70810,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-05-05T13:54:32.468605Z","caller":"traceutil/trace.go:171","msg":"trace[853157720] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5261; }","duration":"465.34319ms","start":"2026-05-05T13:54:32.00315Z","end":"2026-05-05T13:54:32.468493Z","steps":["trace[853157720] 'agreement among raft nodes before linearized reading' (duration: 464.99006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:32.468752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.003127Z","time spent":"465.600716ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-05T13:54:32.55156Z","caller":"traceutil/trace.go:171","msg":"trace[1721706648] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"403.565213ms","start":"2026-05-05T13:54:32.147964Z","end":"2026-05-05T13:54:32.55153Z","steps":["trace[1721706648] 'process raft request' (duration: 403.397739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:32.551744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.147945Z","time spent":"403.695456ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8038,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:33.074339Z","caller":"traceutil/trace.go:171","msg":"trace[702834882] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"787.996316ms","start":"2026-05-05T13:54:32.286303Z","end":"2026-05-05T13:54:33.0743Z","steps":["trace[702834882] 'process raft request' (duration: 787.633576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:33.074762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.286282Z","time spent":"788.311155ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:33.074752Z","caller":"traceutil/trace.go:171","msg":"trace[579947061] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"754.787094ms","start":"2026-05-05T13:54:32.319907Z","end":"2026-05-05T13:54:33.074694Z","steps":["trace[579947061] 'process raft request' (duration: 754.335651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:33.075215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.319892Z","time spent":"755.196306ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:33.075395Z","caller":"traceutil/trace.go:171","msg":"trace[1971269780] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"600.242011ms","start":"2026-05-05T13:54:32.475135Z","end":"2026-05-05T13:54:33.075377Z","steps":["trace[1971269780] 'process raft request' (duration: 599.916812ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:33.075446Z","caller":"traceutil/trace.go:171","msg":"trace[19699408] linearizableReadLoop","detail":"{readStateIndex:5465; appliedIndex:5461; }","duration":"606.985076ms","start":"2026-05-05T13:54:32.468279Z","end":"2026-05-05T13:54:33.075264Z","steps":["trace[19699408] 'read index received' (duration: 83.127043ms)","trace[19699408] 'applied index is now lower than readState.Index' (duration: 523.856823ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:54:33.075533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.47511Z","time spent":"600.373825ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:33.075414Z","caller":"traceutil/trace.go:171","msg":"trace[478407440] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"750.192108ms","start":"2026-05-05T13:54:32.325207Z","end":"2026-05-05T13:54:33.075399Z","steps":["trace[478407440] 'process raft request' (duration: 749.53336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:33.075655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.044338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T13:54:33.075727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.325165Z","time spent":"750.523917ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:33.075774Z","caller":"traceutil/trace.go:171","msg":"trace[751650417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5266; }","duration":"859.217873ms","start":"2026-05-05T13:54:32.216527Z","end":"2026-05-05T13:54:33.075744Z","steps":["trace[751650417] 'agreement among raft nodes before linearized reading' (duration: 858.962276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:33.075826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.21651Z","time spent":"859.309075ms","remote":"127.0.0.1:33862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T13:54:33.419898Z","caller":"traceutil/trace.go:171","msg":"trace[467283502] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"860.386569ms","start":"2026-05-05T13:54:32.559473Z","end":"2026-05-05T13:54:33.419859Z","steps":["trace[467283502] 'process raft request' (duration: 822.369055ms)","trace[467283502] 'compare' (duration: 37.827869ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:54:33.420056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.559447Z","time spent":"860.528962ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:33.420285Z","caller":"traceutil/trace.go:171","msg":"trace[288100480] linearizableReadLoop","detail":"{readStateIndex:5470; appliedIndex:5466; }","duration":"344.67043ms","start":"2026-05-05T13:54:33.075585Z","end":"2026-05-05T13:54:33.420256Z","steps":["trace[288100480] 'read index received' (duration: 306.288656ms)","trace[288100480] 'applied index is now lower than readState.Index' (duration: 38.380834ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:33.420257Z","caller":"traceutil/trace.go:171","msg":"trace[1955191850] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"860.703208ms","start":"2026-05-05T13:54:32.559473Z","end":"2026-05-05T13:54:33.420176Z","steps":["trace[1955191850] 'process raft request' (duration: 860.341048ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:33.420351Z","caller":"traceutil/trace.go:171","msg":"trace[866043228] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"860.159333ms","start":"2026-05-05T13:54:32.560136Z","end":"2026-05-05T13:54:33.420296Z","steps":["trace[866043228] 'process raft request' (duration: 860.065821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:33.420446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.089623ms","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-05-05T13:54:33.420505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.228098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-05-05T13:54:33.420513Z","caller":"traceutil/trace.go:171","msg":"trace[505696270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5270; }","duration":"598.195296ms","start":"2026-05-05T13:54:32.822303Z","end":"2026-05-05T13:54:33.420498Z","steps":["trace[505696270] 'agreement among raft nodes before linearized reading' (duration: 597.98763ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:33.420501Z","caller":"traceutil/trace.go:171","msg":"trace[33179634] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"860.930874ms","start":"2026-05-05T13:54:32.55951Z","end":"2026-05-05T13:54:33.420441Z","steps":["trace[33179634] 'process raft request' (duration: 860.524203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:33.420524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.560122Z","time spent":"860.315177ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1067,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:33.420546Z","caller":"traceutil/trace.go:171","msg":"trace[2073387887] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5270; }","duration":"831.28745ms","start":"2026-05-05T13:54:32.589244Z","end":"2026-05-05T13:54:33.420532Z","steps":["trace[2073387887] 'agreement among raft nodes before linearized reading' (duration: 831.134945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:33.420562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.822283Z","time spent":"598.268007ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T13:54:33.420582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.589221Z","time spent":"831.351922ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2012,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-05-05T13:54:33.420578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.559447Z","time spent":"860.952874ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":681,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:54:33.420628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.559483Z","time spent":"861.080857ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1038,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:54:33.420718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.255585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-8hv98\" ","response":"range_response_count:1 size:8053"} {"level":"warn","ts":"2026-05-05T13:54:33.420734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.426641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T13:54:33.420818Z","caller":"traceutil/trace.go:171","msg":"trace[973367603] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-8hv98; range_end:; response_count:1; response_revision:5270; }","duration":"860.388429ms","start":"2026-05-05T13:54:32.560404Z","end":"2026-05-05T13:54:33.420792Z","steps":["trace[973367603] 'agreement among raft nodes before linearized reading' (duration: 860.135822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:33.420869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.560391Z","time spent":"860.468722ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":8075,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-8hv98\" "} {"level":"info","ts":"2026-05-05T13:54:33.420845Z","caller":"traceutil/trace.go:171","msg":"trace[1754022798] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5270; }","duration":"543.622526ms","start":"2026-05-05T13:54:32.877201Z","end":"2026-05-05T13:54:33.420824Z","steps":["trace[1754022798] 'agreement among raft nodes before linearized reading' (duration: 543.265426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:33.42093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:32.877171Z","time spent":"543.742379ms","remote":"127.0.0.1:34154","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-05-05T13:54:33.426609Z","caller":"traceutil/trace.go:171","msg":"trace[119412322] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"258.146593ms","start":"2026-05-05T13:54:33.168437Z","end":"2026-05-05T13:54:33.426583Z","steps":["trace[119412322] 'process raft request' (duration: 257.763092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:33.426903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.979276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T13:54:33.426943Z","caller":"traceutil/trace.go:171","msg":"trace[1539299620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5271; }","duration":"247.087499ms","start":"2026-05-05T13:54:33.179846Z","end":"2026-05-05T13:54:33.426933Z","steps":["trace[1539299620] 'agreement among raft nodes before linearized reading' (duration: 246.931034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:33.427047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.298737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:54:33.427077Z","caller":"traceutil/trace.go:171","msg":"trace[555860269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5271; }","duration":"349.362328ms","start":"2026-05-05T13:54:33.077707Z","end":"2026-05-05T13:54:33.42707Z","steps":["trace[555860269] 'agreement among raft nodes before linearized reading' (duration: 349.316797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:33.427104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:33.077682Z","time spent":"349.4157ms","remote":"127.0.0.1:33872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T13:54:33.427916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.499917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T13:54:33.428259Z","caller":"traceutil/trace.go:171","msg":"trace[399986183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5271; }","duration":"248.719073ms","start":"2026-05-05T13:54:33.179328Z","end":"2026-05-05T13:54:33.428047Z","steps":["trace[399986183] 'agreement among raft nodes before linearized reading' (duration: 247.533851ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:34.134039Z","caller":"traceutil/trace.go:171","msg":"trace[71901358] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"705.684969ms","start":"2026-05-05T13:54:33.428098Z","end":"2026-05-05T13:54:34.133783Z","steps":["trace[71901358] 'process raft request' (duration: 705.404311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:34.134265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:33.428062Z","time spent":"706.08722ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:34.134457Z","caller":"traceutil/trace.go:171","msg":"trace[1141579613] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"702.976494ms","start":"2026-05-05T13:54:33.431468Z","end":"2026-05-05T13:54:34.134445Z","steps":["trace[1141579613] 'process raft request' (duration: 702.605374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:34.134527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:33.431452Z","time spent":"703.039436ms","remote":"127.0.0.1:34190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1607,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:34.134509Z","caller":"traceutil/trace.go:171","msg":"trace[1769043012] linearizableReadLoop","detail":"{readStateIndex:5478; appliedIndex:5472; }","duration":"700.204618ms","start":"2026-05-05T13:54:33.434271Z","end":"2026-05-05T13:54:34.134476Z","steps":["trace[1769043012] 'read index received' (duration: 75.894226ms)","trace[1769043012] 'applied index is now lower than readState.Index' (duration: 624.308812ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:34.13459Z","caller":"traceutil/trace.go:171","msg":"trace[1478288679] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"703.652343ms","start":"2026-05-05T13:54:33.430884Z","end":"2026-05-05T13:54:34.134536Z","steps":["trace[1478288679] 'process raft request' (duration: 702.847661ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:34.134731Z","caller":"traceutil/trace.go:171","msg":"trace[393469368] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"702.174322ms","start":"2026-05-05T13:54:33.432515Z","end":"2026-05-05T13:54:34.134689Z","steps":["trace[393469368] 'process raft request' (duration: 701.856323ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:34.13476Z","caller":"traceutil/trace.go:171","msg":"trace[1284242449] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"703.067036ms","start":"2026-05-05T13:54:33.43167Z","end":"2026-05-05T13:54:34.134737Z","steps":["trace[1284242449] 'process raft request' (duration: 702.598893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:34.134846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:33.430852Z","time spent":"703.866589ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:54:34.134935Z","caller":"traceutil/trace.go:171","msg":"trace[325196198] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"665.032071ms","start":"2026-05-05T13:54:33.469887Z","end":"2026-05-05T13:54:34.134919Z","steps":["trace[325196198] 'process raft request' (duration: 664.523147ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:34.134892Z","caller":"traceutil/trace.go:171","msg":"trace[166276301] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"702.44596ms","start":"2026-05-05T13:54:33.432406Z","end":"2026-05-05T13:54:34.134852Z","steps":["trace[166276301] 'process raft request' (duration: 701.917565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:34.135026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.754943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:54:34.135059Z","caller":"traceutil/trace.go:171","msg":"trace[1218159324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5279; }","duration":"700.803195ms","start":"2026-05-05T13:54:33.434245Z","end":"2026-05-05T13:54:34.135048Z","steps":["trace[1218159324] 'agreement among raft nodes before linearized reading' (duration: 700.739413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:34.13508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:33.434235Z","time spent":"700.841256ms","remote":"127.0.0.1:33862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T13:54:34.135076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:33.469864Z","time spent":"665.108894ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1408,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:54:34.135101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:33.432398Z","time spent":"702.603864ms","remote":"127.0.0.1:34190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1623,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:54:34.13493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:33.431657Z","time spent":"703.18415ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:54:34.135172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.266545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T13:54:34.13522Z","caller":"traceutil/trace.go:171","msg":"trace[690984281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5279; }","duration":"686.338317ms","start":"2026-05-05T13:54:33.44887Z","end":"2026-05-05T13:54:34.135208Z","steps":["trace[690984281] 'agreement among raft nodes before linearized reading' (duration: 686.151482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:34.135211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.49721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"warn","ts":"2026-05-05T13:54:34.135253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:33.448861Z","time spent":"686.384288ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T13:54:34.13528Z","caller":"traceutil/trace.go:171","msg":"trace[1201230862] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:5279; }","duration":"461.592702ms","start":"2026-05-05T13:54:33.673665Z","end":"2026-05-05T13:54:34.135258Z","steps":["trace[1201230862] 'agreement among raft nodes before linearized reading' (duration: 461.360835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:34.135328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:33.673647Z","time spent":"461.667904ms","remote":"127.0.0.1:34026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1655,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-05-05T13:54:34.135402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:33.43249Z","time spent":"702.347977ms","remote":"127.0.0.1:34190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:54:34.579454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.488566ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:54:34.579657Z","caller":"traceutil/trace.go:171","msg":"trace[656044139] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5280; }","duration":"280.726033ms","start":"2026-05-05T13:54:34.298902Z","end":"2026-05-05T13:54:34.579628Z","steps":["trace[656044139] 'range keys from in-memory index tree' (duration: 280.459696ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:34.580032Z","caller":"traceutil/trace.go:171","msg":"trace[1764971155] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"436.189133ms","start":"2026-05-05T13:54:34.143806Z","end":"2026-05-05T13:54:34.579995Z","steps":["trace[1764971155] 'process raft request' (duration: 427.436013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:34.580263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:34.143786Z","time spent":"436.392629ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":730,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:54:34.580373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.015818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j7zcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T13:54:34.58045Z","caller":"traceutil/trace.go:171","msg":"trace[666674688] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j7zcz; range_end:; response_count:1; response_revision:5281; }","duration":"104.137961ms","start":"2026-05-05T13:54:34.476295Z","end":"2026-05-05T13:54:34.580433Z","steps":["trace[666674688] 'agreement among raft nodes before linearized reading' (duration: 103.852843ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:34.580028Z","caller":"traceutil/trace.go:171","msg":"trace[2023651567] linearizableReadLoop","detail":"{readStateIndex:5481; appliedIndex:5480; }","duration":"103.658048ms","start":"2026-05-05T13:54:34.47633Z","end":"2026-05-05T13:54:34.579988Z","steps":["trace[2023651567] 'read index received' (duration: 94.936708ms)","trace[2023651567] 'applied index is now lower than readState.Index' (duration: 8.71787ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:34.580491Z","caller":"traceutil/trace.go:171","msg":"trace[1902814250] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"103.800912ms","start":"2026-05-05T13:54:34.476661Z","end":"2026-05-05T13:54:34.580462Z","steps":["trace[1902814250] 'process raft request' (duration: 103.662888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:41.10911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.626734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"warn","ts":"2026-05-05T13:54:41.109127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.803567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-05T13:54:41.109188Z","caller":"traceutil/trace.go:171","msg":"trace[1387168797] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5366; }","duration":"116.738708ms","start":"2026-05-05T13:54:40.99243Z","end":"2026-05-05T13:54:41.109169Z","steps":["trace[1387168797] 'agreement among raft nodes before linearized reading' (duration: 116.519942ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:41.109202Z","caller":"traceutil/trace.go:171","msg":"trace[1715478035] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5366; }","duration":"109.90725ms","start":"2026-05-05T13:54:40.999276Z","end":"2026-05-05T13:54:41.109183Z","steps":["trace[1715478035] 'agreement among raft nodes before linearized reading' (duration: 109.655193ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:42.385471Z","caller":"traceutil/trace.go:171","msg":"trace[513386662] linearizableReadLoop","detail":"{readStateIndex:5629; appliedIndex:5628; }","duration":"141.835617ms","start":"2026-05-05T13:54:42.243609Z","end":"2026-05-05T13:54:42.385444Z","steps":["trace[513386662] 'read index received' (duration: 141.528638ms)","trace[513386662] 'applied index is now lower than readState.Index' (duration: 306.099µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:42.385836Z","caller":"traceutil/trace.go:171","msg":"trace[1018935834] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"142.785983ms","start":"2026-05-05T13:54:42.243037Z","end":"2026-05-05T13:54:42.385822Z","steps":["trace[1018935834] 'process raft request' (duration: 142.162746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:42.386269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.642279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-05T13:54:42.386322Z","caller":"traceutil/trace.go:171","msg":"trace[1297234543] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5426; }","duration":"142.747012ms","start":"2026-05-05T13:54:42.243561Z","end":"2026-05-05T13:54:42.386308Z","steps":["trace[1297234543] 'agreement among raft nodes before linearized reading' (duration: 142.554707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:42.551065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.699111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:54:42.551216Z","caller":"traceutil/trace.go:171","msg":"trace[2074846627] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"158.656779ms","start":"2026-05-05T13:54:42.392532Z","end":"2026-05-05T13:54:42.551189Z","steps":["trace[2074846627] 'process raft request' (duration: 43.708291ms)","trace[2074846627] 'compare' (duration: 114.489026ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:42.604515Z","caller":"traceutil/trace.go:171","msg":"trace[1904701190] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"211.660465ms","start":"2026-05-05T13:54:42.392817Z","end":"2026-05-05T13:54:42.604478Z","steps":["trace[1904701190] 'process raft request' (duration: 211.381118ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:42.604569Z","caller":"traceutil/trace.go:171","msg":"trace[396004689] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"209.389953ms","start":"2026-05-05T13:54:42.395149Z","end":"2026-05-05T13:54:42.604538Z","steps":["trace[396004689] 'process raft request' (duration: 209.251509ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:42.6046Z","caller":"traceutil/trace.go:171","msg":"trace[613868653] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"209.250869ms","start":"2026-05-05T13:54:42.395335Z","end":"2026-05-05T13:54:42.604586Z","steps":["trace[613868653] 'process raft request' (duration: 209.109375ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:42.604532Z","caller":"traceutil/trace.go:171","msg":"trace[1878751299] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"209.63758ms","start":"2026-05-05T13:54:42.394867Z","end":"2026-05-05T13:54:42.604504Z","steps":["trace[1878751299] 'process raft request' (duration: 209.497566ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:42.604696Z","caller":"traceutil/trace.go:171","msg":"trace[1235392852] linearizableReadLoop","detail":"{readStateIndex:5631; appliedIndex:5629; }","duration":"211.45962ms","start":"2026-05-05T13:54:42.393207Z","end":"2026-05-05T13:54:42.604667Z","steps":["trace[1235392852] 'read index received' (duration: 42.932179ms)","trace[1235392852] 'applied index is now lower than readState.Index' (duration: 168.525971ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:42.60476Z","caller":"traceutil/trace.go:171","msg":"trace[564326147] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"209.28083ms","start":"2026-05-05T13:54:42.395468Z","end":"2026-05-05T13:54:42.604748Z","steps":["trace[564326147] 'process raft request' (duration: 209.049293ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:42.604895Z","caller":"traceutil/trace.go:171","msg":"trace[104161732] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"209.536707ms","start":"2026-05-05T13:54:42.395348Z","end":"2026-05-05T13:54:42.604884Z","steps":["trace[104161732] 'process raft request' (duration: 209.134255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:42.60502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.769348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-05T13:54:42.605084Z","caller":"traceutil/trace.go:171","msg":"trace[542449452] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5434; }","duration":"211.899682ms","start":"2026-05-05T13:54:42.39317Z","end":"2026-05-05T13:54:42.605069Z","steps":["trace[542449452] 'agreement among raft nodes before linearized reading' (duration: 211.654505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:42.605117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.585614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8bfb866-071c-43bf-a63c-9afff5c9dd1a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T13:54:42.605189Z","caller":"traceutil/trace.go:171","msg":"trace[752281172] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8bfb866-071c-43bf-a63c-9afff5c9dd1a; range_end:; response_count:1; response_revision:5434; }","duration":"203.675506ms","start":"2026-05-05T13:54:42.401495Z","end":"2026-05-05T13:54:42.60517Z","steps":["trace[752281172] 'agreement among raft nodes before linearized reading' (duration: 203.528972ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:42.60528Z","caller":"traceutil/trace.go:171","msg":"trace[1025471819] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"202.565015ms","start":"2026-05-05T13:54:42.402705Z","end":"2026-05-05T13:54:42.60527Z","steps":["trace[1025471819] 'process raft request' (duration: 201.868536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:42.606111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.755902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2212891"} {"level":"info","ts":"2026-05-05T13:54:42.606171Z","caller":"traceutil/trace.go:171","msg":"trace[1234684570] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5434; }","duration":"146.819964ms","start":"2026-05-05T13:54:42.459342Z","end":"2026-05-05T13:54:42.606162Z","steps":["trace[1234684570] 'agreement among raft nodes before linearized reading' (duration: 146.062463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:42.841126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.795584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T13:54:42.841281Z","caller":"traceutil/trace.go:171","msg":"trace[1340954332] linearizableReadLoop","detail":"{readStateIndex:5638; appliedIndex:5637; }","duration":"227.500651ms","start":"2026-05-05T13:54:42.613757Z","end":"2026-05-05T13:54:42.841257Z","steps":["trace[1340954332] 'read index received' (duration: 112.562573ms)","trace[1340954332] 'applied index is now lower than readState.Index' (duration: 114.935858ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:42.841456Z","caller":"traceutil/trace.go:171","msg":"trace[1657754264] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"230.038661ms","start":"2026-05-05T13:54:42.611322Z","end":"2026-05-05T13:54:42.84136Z","steps":["trace[1657754264] 'process raft request' (duration: 114.899277ms)","trace[1657754264] 'compare' (duration: 114.557158ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:54:42.841845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.046306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-05T13:54:42.84199Z","caller":"traceutil/trace.go:171","msg":"trace[1460944550] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5435; }","duration":"228.239951ms","start":"2026-05-05T13:54:42.613715Z","end":"2026-05-05T13:54:42.841955Z","steps":["trace[1460944550] 'agreement among raft nodes before linearized reading' (duration: 227.778058ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:43.022548Z","caller":"traceutil/trace.go:171","msg":"trace[1977775000] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"408.716339ms","start":"2026-05-05T13:54:42.613779Z","end":"2026-05-05T13:54:43.022495Z","steps":["trace[1977775000] 'process raft request' (duration: 338.440008ms)","trace[1977775000] 'compare' (duration: 69.358086ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:54:43.022722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:42.613758Z","time spent":"408.879374ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":902,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T13:54:43.023008Z","caller":"traceutil/trace.go:171","msg":"trace[1683981127] linearizableReadLoop","detail":"{readStateIndex:5641; appliedIndex:5638; }","duration":"181.650791ms","start":"2026-05-05T13:54:42.841311Z","end":"2026-05-05T13:54:43.022962Z","steps":["trace[1683981127] 'read index received' (duration: 110.808835ms)","trace[1683981127] 'applied index is now lower than readState.Index' (duration: 70.839346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:54:43.02315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.29355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:54:43.023199Z","caller":"traceutil/trace.go:171","msg":"trace[1225885582] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"402.834878ms","start":"2026-05-05T13:54:42.620319Z","end":"2026-05-05T13:54:43.023154Z","steps":["trace[1225885582] 'process raft request' (duration: 402.516599ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:43.023252Z","caller":"traceutil/trace.go:171","msg":"trace[1603447365] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:5438; }","duration":"316.464085ms","start":"2026-05-05T13:54:42.706775Z","end":"2026-05-05T13:54:43.023239Z","steps":["trace[1603447365] 'agreement among raft nodes before linearized reading' (duration: 316.30473ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:43.023342Z","caller":"traceutil/trace.go:171","msg":"trace[1344371713] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"406.317174ms","start":"2026-05-05T13:54:42.616983Z","end":"2026-05-05T13:54:43.0233Z","steps":["trace[1344371713] 'process raft request' (duration: 405.442809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:43.023407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:42.620306Z","time spent":"402.985152ms","remote":"127.0.0.1:40394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7067,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:54:43.023571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.089845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j7zcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T13:54:43.023692Z","caller":"traceutil/trace.go:171","msg":"trace[1626275084] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j7zcz; range_end:; response_count:1; response_revision:5438; }","duration":"399.28723ms","start":"2026-05-05T13:54:42.624386Z","end":"2026-05-05T13:54:43.023674Z","steps":["trace[1626275084] 'agreement among raft nodes before linearized reading' (duration: 398.839347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:43.023737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:42.62435Z","time spent":"399.376552ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-j7zcz\" "} {"level":"warn","ts":"2026-05-05T13:54:43.023707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.684767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-05-05T13:54:43.023825Z","caller":"traceutil/trace.go:171","msg":"trace[487227103] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5438; }","duration":"389.8332ms","start":"2026-05-05T13:54:42.63396Z","end":"2026-05-05T13:54:43.023793Z","steps":["trace[487227103] 'agreement among raft nodes before linearized reading' (duration: 389.516101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:43.023893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:42.633948Z","time spent":"389.932313ms","remote":"127.0.0.1:33956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1839,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-05T13:54:43.023295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:42.706742Z","time spent":"316.542417ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-05-05T13:54:43.023512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:42.616961Z","time spent":"406.456047ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:54:43.024332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.213251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110689"} {"level":"warn","ts":"2026-05-05T13:54:43.02445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.429979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8bfb866-071c-43bf-a63c-9afff5c9dd1a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T13:54:43.024543Z","caller":"traceutil/trace.go:171","msg":"trace[1977912007] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8bfb866-071c-43bf-a63c-9afff5c9dd1a; range_end:; response_count:1; response_revision:5438; }","duration":"409.545702ms","start":"2026-05-05T13:54:42.614974Z","end":"2026-05-05T13:54:43.02452Z","steps":["trace[1977912007] 'agreement among raft nodes before linearized reading' (duration: 408.36422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:54:43.024677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:54:42.614926Z","time spent":"409.733027ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8bfb866-071c-43bf-a63c-9afff5c9dd1a\" "} {"level":"info","ts":"2026-05-05T13:54:43.024487Z","caller":"traceutil/trace.go:171","msg":"trace[1000300282] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5438; }","duration":"176.452498ms","start":"2026-05-05T13:54:42.848013Z","end":"2026-05-05T13:54:43.024465Z","steps":["trace[1000300282] 'agreement among raft nodes before linearized reading' (duration: 175.514922ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:54:50.378Z","caller":"traceutil/trace.go:171","msg":"trace[951252246] linearizableReadLoop","detail":"{readStateIndex:5774; appliedIndex:5773; }","duration":"141.063276ms","start":"2026-05-05T13:54:50.236911Z","end":"2026-05-05T13:54:50.377974Z","steps":["trace[951252246] 'read index received' (duration: 101.88786ms)","trace[951252246] 'applied index is now lower than readState.Index' (duration: 39.174286ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:54:50.378109Z","caller":"traceutil/trace.go:171","msg":"trace[2145665511] transaction","detail":"{read_only:false; response_revision:5567; number_of_response:1; }","duration":"210.253406ms","start":"2026-05-05T13:54:50.1678Z","end":"2026-05-05T13:54:50.378054Z","steps":["trace[2145665511] 'process raft request' (duration: 171.058189ms)","trace[2145665511] 'compare' (duration: 38.91514ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:54:50.378485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.547339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T13:54:50.378639Z","caller":"traceutil/trace.go:171","msg":"trace[96818380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5567; }","duration":"141.792106ms","start":"2026-05-05T13:54:50.236823Z","end":"2026-05-05T13:54:50.378615Z","steps":["trace[96818380] 'agreement among raft nodes before linearized reading' (duration: 141.308182ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:55:46.824543Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2992} {"level":"info","ts":"2026-05-05T13:55:46.893366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2992,"took":"67.167555ms","hash":155436975,"current-db-size-bytes":56930304,"current-db-size":"57 MB","current-db-size-in-use-bytes":33525760,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T13:55:46.893426Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":155436975,"revision":2992,"compact-revision":-1} {"level":"warn","ts":"2026-05-05T13:59:36.122072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5264318704585086816,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T13:59:36.622923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5264318704585086816,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T13:59:36.663872Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.180447597s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T13:59:36.671301Z","caller":"traceutil/trace.go:171","msg":"trace[1548453071] transaction","detail":"{read_only:false; response_revision:7653; number_of_response:1; }","duration":"1.171132532s","start":"2026-05-05T13:59:35.5001Z","end":"2026-05-05T13:59:36.671233Z","steps":["trace[1548453071] 'process raft request' (duration: 1.170975708s)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:59:36.671575Z","caller":"traceutil/trace.go:171","msg":"trace[1344537732] transaction","detail":"{read_only:false; response_revision:7654; number_of_response:1; }","duration":"497.01532ms","start":"2026-05-05T13:59:36.174533Z","end":"2026-05-05T13:59:36.671548Z","steps":["trace[1344537732] 'process raft request' (duration: 496.6648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:59:36.671739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:59:36.174511Z","time spent":"497.146143ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:59:36.671783Z","caller":"traceutil/trace.go:171","msg":"trace[1283031111] transaction","detail":"{read_only:false; response_revision:7656; number_of_response:1; }","duration":"311.059962ms","start":"2026-05-05T13:59:36.360703Z","end":"2026-05-05T13:59:36.671763Z","steps":["trace[1283031111] 'process raft request' (duration: 310.97212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:59:36.671891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:59:35.500078Z","time spent":"1.171281636s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:59:36.671958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:59:36.360655Z","time spent":"311.212847ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:59:36.671961Z","caller":"traceutil/trace.go:171","msg":"trace[1024784972] transaction","detail":"{read_only:false; response_revision:7657; number_of_response:1; }","duration":"227.54595ms","start":"2026-05-05T13:59:36.444403Z","end":"2026-05-05T13:59:36.671949Z","steps":["trace[1024784972] 'process raft request' (duration: 227.329894ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:59:36.671939Z","caller":"traceutil/trace.go:171","msg":"trace[181959098] linearizableReadLoop","detail":"{readStateIndex:7932; appliedIndex:7926; }","duration":"1.054775856s","start":"2026-05-05T13:59:35.617097Z","end":"2026-05-05T13:59:36.671872Z","steps":["trace[181959098] 'read index received' (duration: 1.047151367s)","trace[181959098] 'applied index is now lower than readState.Index' (duration: 7.623169ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:59:36.672038Z","caller":"traceutil/trace.go:171","msg":"trace[941151236] transaction","detail":"{read_only:false; response_revision:7655; number_of_response:1; }","duration":"313.402327ms","start":"2026-05-05T13:59:36.358626Z","end":"2026-05-05T13:59:36.672028Z","steps":["trace[941151236] 'process raft request' (duration: 312.830981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:59:36.672145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:59:36.358608Z","time spent":"313.51406ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T13:59:36.67229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055199488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:59:36.672339Z","caller":"traceutil/trace.go:171","msg":"trace[212964935] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:7657; }","duration":"1.055306411s","start":"2026-05-05T13:59:35.617021Z","end":"2026-05-05T13:59:36.672328Z","steps":["trace[212964935] 'agreement among raft nodes before linearized reading' (duration: 1.055211218s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:59:36.672382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:59:35.616995Z","time spent":"1.055372423s","remote":"127.0.0.1:46682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":22,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-05-05T13:59:36.672471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.547404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T13:59:36.67342Z","caller":"traceutil/trace.go:171","msg":"trace[954331489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7657; }","duration":"750.545572ms","start":"2026-05-05T13:59:35.922863Z","end":"2026-05-05T13:59:36.673408Z","steps":["trace[954331489] 'agreement among raft nodes before linearized reading' (duration: 749.40458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:59:36.673443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.77218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T13:59:36.673478Z","caller":"traceutil/trace.go:171","msg":"trace[1933573497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7657; }","duration":"745.830112ms","start":"2026-05-05T13:59:35.927638Z","end":"2026-05-05T13:59:36.673468Z","steps":["trace[1933573497] 'agreement among raft nodes before linearized reading' (duration: 745.712799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:59:36.673514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:59:35.927625Z","time spent":"745.881073ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T13:59:36.673704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.673565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T13:59:36.673738Z","caller":"traceutil/trace.go:171","msg":"trace[943534502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7657; }","duration":"608.748847ms","start":"2026-05-05T13:59:36.06498Z","end":"2026-05-05T13:59:36.673729Z","steps":["trace[943534502] 'agreement among raft nodes before linearized reading' (duration: 608.656505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:59:36.673866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:59:36.064962Z","time spent":"608.890172ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T13:59:36.6731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.856389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T13:59:36.673929Z","caller":"traceutil/trace.go:171","msg":"trace[1282186866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7657; }","duration":"452.725693ms","start":"2026-05-05T13:59:36.221194Z","end":"2026-05-05T13:59:36.67392Z","steps":["trace[1282186866] 'agreement among raft nodes before linearized reading' (duration: 451.873559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:59:36.674064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:59:36.221175Z","time spent":"452.879717ms","remote":"127.0.0.1:33862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T13:59:36.673873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:59:35.922841Z","time spent":"750.608604ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T13:59:36.674267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.496333ms","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-05-05T13:59:36.674299Z","caller":"traceutil/trace.go:171","msg":"trace[1151983530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7657; }","duration":"749.560295ms","start":"2026-05-05T13:59:35.924731Z","end":"2026-05-05T13:59:36.674291Z","steps":["trace[1151983530] 'agreement among raft nodes before linearized reading' (duration: 749.488113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:59:36.674323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:59:35.924713Z","time spent":"749.602265ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T14:00:46.830451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6042} {"level":"info","ts":"2026-05-05T14:00:46.936714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6042,"took":"104.268274ms","hash":534171664,"current-db-size-bytes":56930304,"current-db-size":"57 MB","current-db-size-in-use-bytes":23699456,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-05T14:00:46.936802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":534171664,"revision":6042,"compact-revision":2992} {"level":"info","ts":"2026-05-05T14:00:49.070477Z","caller":"traceutil/trace.go:171","msg":"trace[1219003888] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"148.533138ms","start":"2026-05-05T14:00:48.92193Z","end":"2026-05-05T14:00:49.070463Z","steps":["trace[1219003888] 'process raft request' (duration: 148.425226ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:49.441454Z","caller":"traceutil/trace.go:171","msg":"trace[170222003] transaction","detail":"{read_only:false; response_revision:8269; number_of_response:1; }","duration":"449.376861ms","start":"2026-05-05T14:00:48.992051Z","end":"2026-05-05T14:00:49.441428Z","steps":["trace[170222003] 'process raft request' (duration: 449.180966ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:49.441831Z","caller":"traceutil/trace.go:171","msg":"trace[646591660] linearizableReadLoop","detail":"{readStateIndex:8568; appliedIndex:8563; }","duration":"334.533797ms","start":"2026-05-05T14:00:49.107268Z","end":"2026-05-05T14:00:49.441802Z","steps":["trace[646591660] 'read index received' (duration: 333.91324ms)","trace[646591660] 'applied index is now lower than readState.Index' (duration: 619.017µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:00:49.441861Z","caller":"traceutil/trace.go:171","msg":"trace[1099681636] transaction","detail":"{read_only:false; response_revision:8272; number_of_response:1; }","duration":"377.516188ms","start":"2026-05-05T14:00:49.064306Z","end":"2026-05-05T14:00:49.441822Z","steps":["trace[1099681636] 'process raft request' (duration: 377.385304ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:49.441936Z","caller":"traceutil/trace.go:171","msg":"trace[2051348225] transaction","detail":"{read_only:false; response_revision:8271; number_of_response:1; }","duration":"385.470555ms","start":"2026-05-05T14:00:49.056448Z","end":"2026-05-05T14:00:49.441919Z","steps":["trace[2051348225] 'process raft request' (duration: 385.142186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:49.442004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:49.064286Z","time spent":"377.636291ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:00:49.442056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.782744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T14:00:49.44208Z","caller":"traceutil/trace.go:171","msg":"trace[450895887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8273; }","duration":"334.843976ms","start":"2026-05-05T14:00:49.107228Z","end":"2026-05-05T14:00:49.442072Z","steps":["trace[450895887] 'agreement among raft nodes before linearized reading' (duration: 334.734693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:49.442101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:49.107213Z","time spent":"334.882926ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T14:00:49.44215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:49.056429Z","time spent":"385.567939ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:00:49.442251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.799492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-05T14:00:49.442296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.84751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:00:49.442304Z","caller":"traceutil/trace.go:171","msg":"trace[1521539192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8273; }","duration":"311.864325ms","start":"2026-05-05T14:00:49.130424Z","end":"2026-05-05T14:00:49.442289Z","steps":["trace[1521539192] 'agreement among raft nodes before linearized reading' (duration: 311.768152ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:49.441948Z","caller":"traceutil/trace.go:171","msg":"trace[1412749112] transaction","detail":"{read_only:false; response_revision:8273; number_of_response:1; }","duration":"372.098239ms","start":"2026-05-05T14:00:49.069842Z","end":"2026-05-05T14:00:49.44194Z","steps":["trace[1412749112] 'process raft request' (duration: 371.911034ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:00:49.442328Z","caller":"traceutil/trace.go:171","msg":"trace[1670368336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8273; }","duration":"226.908301ms","start":"2026-05-05T14:00:49.215409Z","end":"2026-05-05T14:00:49.442317Z","steps":["trace[1670368336] 'agreement among raft nodes before linearized reading' (duration: 226.86664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:49.442343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:49.130415Z","time spent":"311.920086ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T14:00:49.442396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:49.069832Z","time spent":"372.525211ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:00:49.442252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.31942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j7zcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T14:00:49.441811Z","caller":"traceutil/trace.go:171","msg":"trace[155663411] transaction","detail":"{read_only:false; response_revision:8270; number_of_response:1; }","duration":"438.234385ms","start":"2026-05-05T14:00:49.003526Z","end":"2026-05-05T14:00:49.44176Z","steps":["trace[155663411] 'process raft request' (duration: 437.854085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:49.442158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.133537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T14:00:49.442488Z","caller":"traceutil/trace.go:171","msg":"trace[630779636] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j7zcz; range_end:; response_count:1; response_revision:8273; }","duration":"295.593968ms","start":"2026-05-05T14:00:49.146883Z","end":"2026-05-05T14:00:49.442477Z","steps":["trace[630779636] 'agreement among raft nodes before linearized reading' (duration: 295.278859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:49.442481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.889867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T14:00:49.442511Z","caller":"traceutil/trace.go:171","msg":"trace[623004338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8273; }","duration":"334.510116ms","start":"2026-05-05T14:00:49.107986Z","end":"2026-05-05T14:00:49.442496Z","steps":["trace[623004338] 'agreement among raft nodes before linearized reading' (duration: 334.040623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:49.44252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:49.003489Z","time spent":"438.995206ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:00:49.442564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:49.107975Z","time spent":"334.576618ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T14:00:49.442561Z","caller":"traceutil/trace.go:171","msg":"trace[120075437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8273; }","duration":"326.98962ms","start":"2026-05-05T14:00:49.11555Z","end":"2026-05-05T14:00:49.44254Z","steps":["trace[120075437] 'agreement among raft nodes before linearized reading' (duration: 326.787364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:00:49.442626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:49.115539Z","time spent":"327.073073ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T14:00:49.442805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:00:48.992027Z","time spent":"449.539526ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T14:01:01.674718Z","caller":"traceutil/trace.go:171","msg":"trace[991382581] transaction","detail":"{read_only:false; response_revision:8334; number_of_response:1; }","duration":"182.79623ms","start":"2026-05-05T14:01:01.491898Z","end":"2026-05-05T14:01:01.674694Z","steps":["trace[991382581] 'process raft request' (duration: 182.712317ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:01.674718Z","caller":"traceutil/trace.go:171","msg":"trace[992334511] transaction","detail":"{read_only:false; response_revision:8333; number_of_response:1; }","duration":"190.594274ms","start":"2026-05-05T14:01:01.484082Z","end":"2026-05-05T14:01:01.674676Z","steps":["trace[992334511] 'process raft request' (duration: 190.363728ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:01.674941Z","caller":"traceutil/trace.go:171","msg":"trace[1815763089] linearizableReadLoop","detail":"{readStateIndex:8633; appliedIndex:8629; }","duration":"169.145995ms","start":"2026-05-05T14:01:01.50577Z","end":"2026-05-05T14:01:01.674916Z","steps":["trace[1815763089] 'read index received' (duration: 24.353638ms)","trace[1815763089] 'applied index is now lower than readState.Index' (duration: 144.790407ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:01:01.675034Z","caller":"traceutil/trace.go:171","msg":"trace[1264035652] transaction","detail":"{read_only:false; response_revision:8335; number_of_response:1; }","duration":"179.737776ms","start":"2026-05-05T14:01:01.49523Z","end":"2026-05-05T14:01:01.674967Z","steps":["trace[1264035652] 'process raft request' (duration: 179.424448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:01.675182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.397302ms","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-05-05T14:01:01.67525Z","caller":"traceutil/trace.go:171","msg":"trace[2051403357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8335; }","duration":"169.498375ms","start":"2026-05-05T14:01:01.505719Z","end":"2026-05-05T14:01:01.675218Z","steps":["trace[2051403357] 'agreement among raft nodes before linearized reading' (duration: 169.262539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:01.675327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.10047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T14:01:01.675368Z","caller":"traceutil/trace.go:171","msg":"trace[134319000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8335; }","duration":"155.159171ms","start":"2026-05-05T14:01:01.520196Z","end":"2026-05-05T14:01:01.675355Z","steps":["trace[134319000] 'agreement among raft nodes before linearized reading' (duration: 155.038928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:01.675412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.754618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j7zcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T14:01:01.675453Z","caller":"traceutil/trace.go:171","msg":"trace[1653077322] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j7zcz; range_end:; response_count:1; response_revision:8335; }","duration":"147.829079ms","start":"2026-05-05T14:01:01.527612Z","end":"2026-05-05T14:01:01.675441Z","steps":["trace[1653077322] 'agreement among raft nodes before linearized reading' (duration: 147.605213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:01.675434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.702563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T14:01:01.675527Z","caller":"traceutil/trace.go:171","msg":"trace[1722397747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8335; }","duration":"164.872658ms","start":"2026-05-05T14:01:01.510629Z","end":"2026-05-05T14:01:01.675501Z","steps":["trace[1722397747] 'agreement among raft nodes before linearized reading' (duration: 164.516898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:01.675429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.699344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T14:01:01.675692Z","caller":"traceutil/trace.go:171","msg":"trace[411612717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8335; }","duration":"160.95256ms","start":"2026-05-05T14:01:01.514682Z","end":"2026-05-05T14:01:01.675635Z","steps":["trace[411612717] 'agreement among raft nodes before linearized reading' (duration: 160.631691ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:03.717414Z","caller":"traceutil/trace.go:171","msg":"trace[747473917] transaction","detail":"{read_only:false; response_revision:8343; number_of_response:1; }","duration":"181.809186ms","start":"2026-05-05T14:01:03.535576Z","end":"2026-05-05T14:01:03.717385Z","steps":["trace[747473917] 'process raft request' (duration: 181.633831ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:03.816139Z","caller":"traceutil/trace.go:171","msg":"trace[1670213077] transaction","detail":"{read_only:false; response_revision:8344; number_of_response:1; }","duration":"137.126001ms","start":"2026-05-05T14:01:03.678981Z","end":"2026-05-05T14:01:03.816107Z","steps":["trace[1670213077] 'process raft request' (duration: 134.932551ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:03.816304Z","caller":"traceutil/trace.go:171","msg":"trace[581589837] transaction","detail":"{read_only:false; response_revision:8345; number_of_response:1; }","duration":"135.853956ms","start":"2026-05-05T14:01:03.680436Z","end":"2026-05-05T14:01:03.81629Z","steps":["trace[581589837] 'process raft request' (duration: 135.596359ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:03.816358Z","caller":"traceutil/trace.go:171","msg":"trace[456141092] transaction","detail":"{read_only:false; response_revision:8346; number_of_response:1; }","duration":"135.847806ms","start":"2026-05-05T14:01:03.68048Z","end":"2026-05-05T14:01:03.816328Z","steps":["trace[456141092] 'process raft request' (duration: 135.715312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:03.816575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.556434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T14:01:03.816702Z","caller":"traceutil/trace.go:171","msg":"trace[97016995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8346; }","duration":"117.717628ms","start":"2026-05-05T14:01:03.698961Z","end":"2026-05-05T14:01:03.816679Z","steps":["trace[97016995] 'agreement among raft nodes before linearized reading' (duration: 117.461531ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:30.320337Z","caller":"traceutil/trace.go:171","msg":"trace[101930141] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"321.912948ms","start":"2026-05-05T14:01:29.998389Z","end":"2026-05-05T14:01:30.320302Z","steps":["trace[101930141] 'process raft request' (duration: 321.727213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:30.32055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:29.998369Z","time spent":"322.084013ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T14:01:30.381249Z","caller":"traceutil/trace.go:171","msg":"trace[1556249714] linearizableReadLoop","detail":"{readStateIndex:8844; appliedIndex:8843; }","duration":"378.649984ms","start":"2026-05-05T14:01:30.00257Z","end":"2026-05-05T14:01:30.38122Z","steps":["trace[1556249714] 'read index received' (duration: 318.402872ms)","trace[1556249714] 'applied index is now lower than readState.Index' (duration: 60.245842ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:01:30.38164Z","caller":"traceutil/trace.go:171","msg":"trace[1523411629] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"383.158678ms","start":"2026-05-05T14:01:29.998468Z","end":"2026-05-05T14:01:30.381626Z","steps":["trace[1523411629] 'process raft request' (duration: 382.591713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:30.381771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:29.998421Z","time spent":"383.261021ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T14:01:30.384038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.457782ms","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-05-05T14:01:30.384141Z","caller":"traceutil/trace.go:171","msg":"trace[1057507019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8539; }","duration":"381.600336ms","start":"2026-05-05T14:01:30.002526Z","end":"2026-05-05T14:01:30.384126Z","steps":["trace[1057507019] 'agreement among raft nodes before linearized reading' (duration: 379.483138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:30.38418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:30.002514Z","time spent":"381.657727ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T14:01:30.384447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.31677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j7zcz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-05T14:01:30.384488Z","caller":"traceutil/trace.go:171","msg":"trace[2016452777] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j7zcz; range_end:; response_count:1; response_revision:8539; }","duration":"380.370101ms","start":"2026-05-05T14:01:30.004109Z","end":"2026-05-05T14:01:30.384479Z","steps":["trace[2016452777] 'agreement among raft nodes before linearized reading' (duration: 380.244048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:30.384519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:30.0041Z","time spent":"380.412083ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-j7zcz\" "} {"level":"warn","ts":"2026-05-05T14:01:30.384861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.866036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.241\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T14:01:30.384905Z","caller":"traceutil/trace.go:171","msg":"trace[1054438587] range","detail":"{range_begin:/registry/masterleases/199.19.213.241; range_end:; response_count:1; response_revision:8539; }","duration":"314.961638ms","start":"2026-05-05T14:01:30.069934Z","end":"2026-05-05T14:01:30.384896Z","steps":["trace[1054438587] 'agreement among raft nodes before linearized reading' (duration: 314.858375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:30.384936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:30.069917Z","time spent":"315.012199ms","remote":"127.0.0.1:33886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.241\" "} {"level":"warn","ts":"2026-05-05T14:01:30.385127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.502035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T14:01:30.385164Z","caller":"traceutil/trace.go:171","msg":"trace[779204311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8539; }","duration":"349.548526ms","start":"2026-05-05T14:01:30.035608Z","end":"2026-05-05T14:01:30.385156Z","steps":["trace[779204311] 'agreement among raft nodes before linearized reading' (duration: 349.455953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:30.385192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:30.035602Z","time spent":"349.584077ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T14:01:30.38536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.743269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T14:01:30.385397Z","caller":"traceutil/trace.go:171","msg":"trace[1086379386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8539; }","duration":"350.7972ms","start":"2026-05-05T14:01:30.034591Z","end":"2026-05-05T14:01:30.385388Z","steps":["trace[1086379386] 'agreement among raft nodes before linearized reading' (duration: 350.706128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:30.385424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:30.03458Z","time spent":"350.838291ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T14:01:30.385589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.140375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T14:01:30.385627Z","caller":"traceutil/trace.go:171","msg":"trace[1642466551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8539; }","duration":"353.190926ms","start":"2026-05-05T14:01:30.032425Z","end":"2026-05-05T14:01:30.385616Z","steps":["trace[1642466551] 'agreement among raft nodes before linearized reading' (duration: 353.098793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:30.385655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:30.032415Z","time spent":"353.233417ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T14:01:30.385817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.674167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T14:01:30.385852Z","caller":"traceutil/trace.go:171","msg":"trace[1421495222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8539; }","duration":"354.745629ms","start":"2026-05-05T14:01:30.031098Z","end":"2026-05-05T14:01:30.385843Z","steps":["trace[1421495222] 'agreement among raft nodes before linearized reading' (duration: 354.664127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:30.385879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:30.031079Z","time spent":"354.79362ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T14:01:30.641276Z","caller":"traceutil/trace.go:171","msg":"trace[2100149268] linearizableReadLoop","detail":"{readStateIndex:8845; appliedIndex:8844; }","duration":"247.891438ms","start":"2026-05-05T14:01:30.393355Z","end":"2026-05-05T14:01:30.641247Z","steps":["trace[2100149268] 'read index received' (duration: 158.6339ms)","trace[2100149268] 'applied index is now lower than readState.Index' (duration: 89.255718ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:01:30.641735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.355811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228860"} {"level":"info","ts":"2026-05-05T14:01:30.641819Z","caller":"traceutil/trace.go:171","msg":"trace[1247343679] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8539; }","duration":"248.479104ms","start":"2026-05-05T14:01:30.393323Z","end":"2026-05-05T14:01:30.641802Z","steps":["trace[1247343679] 'agreement among raft nodes before linearized reading' (duration: 248.010312ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:30.655781Z","caller":"traceutil/trace.go:171","msg":"trace[1806960123] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"262.299544ms","start":"2026-05-05T14:01:30.393441Z","end":"2026-05-05T14:01:30.655741Z","steps":["trace[1806960123] 'process raft request' (duration: 261.976385ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:30.656112Z","caller":"traceutil/trace.go:171","msg":"trace[194479752] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"262.55648ms","start":"2026-05-05T14:01:30.393529Z","end":"2026-05-05T14:01:30.656086Z","steps":["trace[194479752] 'process raft request' (duration: 262.143609ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:30.656242Z","caller":"traceutil/trace.go:171","msg":"trace[150818112] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"262.638903ms","start":"2026-05-05T14:01:30.393574Z","end":"2026-05-05T14:01:30.656213Z","steps":["trace[150818112] 'process raft request' (duration: 262.354475ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:30.656301Z","caller":"traceutil/trace.go:171","msg":"trace[1010945675] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"262.709745ms","start":"2026-05-05T14:01:30.393577Z","end":"2026-05-05T14:01:30.656287Z","steps":["trace[1010945675] 'process raft request' (duration: 262.475698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:30.656383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.551755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-05T14:01:30.656414Z","caller":"traceutil/trace.go:171","msg":"trace[1590970226] transaction","detail":"{read_only:false; response_revision:8544; number_of_response:1; }","duration":"262.785617ms","start":"2026-05-05T14:01:30.393615Z","end":"2026-05-05T14:01:30.656401Z","steps":["trace[1590970226] 'process raft request' (duration: 262.5335ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:30.656463Z","caller":"traceutil/trace.go:171","msg":"trace[1081973463] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8544; }","duration":"167.663049ms","start":"2026-05-05T14:01:30.488776Z","end":"2026-05-05T14:01:30.656439Z","steps":["trace[1081973463] 'agreement among raft nodes before linearized reading' (duration: 167.439333ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:44.003138Z","caller":"traceutil/trace.go:171","msg":"trace[1357740105] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"140.846263ms","start":"2026-05-05T14:01:43.862263Z","end":"2026-05-05T14:01:44.003109Z","steps":["trace[1357740105] 'process raft request' (duration: 140.685099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:44.332379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.041544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T14:01:44.332726Z","caller":"traceutil/trace.go:171","msg":"trace[1723764399] linearizableReadLoop","detail":"{readStateIndex:8965; appliedIndex:8964; }","duration":"116.970648ms","start":"2026-05-05T14:01:44.215736Z","end":"2026-05-05T14:01:44.332706Z","steps":["trace[1723764399] 'read index received' (duration: 40.541µs)","trace[1723764399] 'applied index is now lower than readState.Index' (duration: 116.928487ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:01:44.332798Z","caller":"traceutil/trace.go:171","msg":"trace[752918831] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"307.38082ms","start":"2026-05-05T14:01:44.025347Z","end":"2026-05-05T14:01:44.332728Z","steps":["trace[752918831] 'process raft request' (duration: 136.73596ms)","trace[752918831] 'compare' (duration: 169.769616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:01:44.332859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.097001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:01:44.332921Z","caller":"traceutil/trace.go:171","msg":"trace[972812069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8654; }","duration":"117.186863ms","start":"2026-05-05T14:01:44.215718Z","end":"2026-05-05T14:01:44.332905Z","steps":["trace[972812069] 'agreement among raft nodes before linearized reading' (duration: 117.05464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:44.333044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T14:01:44.025324Z","time spent":"307.590776ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T14:01:44.379514Z","caller":"traceutil/trace.go:171","msg":"trace[487998174] transaction","detail":"{read_only:false; response_revision:8655; number_of_response:1; }","duration":"140.776801ms","start":"2026-05-05T14:01:44.238673Z","end":"2026-05-05T14:01:44.379449Z","steps":["trace[487998174] 'process raft request' (duration: 140.527134ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:44.390568Z","caller":"traceutil/trace.go:171","msg":"trace[277827434] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"144.41243ms","start":"2026-05-05T14:01:44.246132Z","end":"2026-05-05T14:01:44.390545Z","steps":["trace[277827434] 'process raft request' (duration: 144.262616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:44.39067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.990308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-gqxdq\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-05-05T14:01:44.390717Z","caller":"traceutil/trace.go:171","msg":"trace[78838082] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-gqxdq; range_end:; response_count:1; response_revision:8656; }","duration":"152.085001ms","start":"2026-05-05T14:01:44.23862Z","end":"2026-05-05T14:01:44.390705Z","steps":["trace[78838082] 'agreement among raft nodes before linearized reading' (duration: 151.932416ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:44.565314Z","caller":"traceutil/trace.go:171","msg":"trace[681515795] linearizableReadLoop","detail":"{readStateIndex:8968; appliedIndex:8967; }","duration":"144.76361ms","start":"2026-05-05T14:01:44.420524Z","end":"2026-05-05T14:01:44.565288Z","steps":["trace[681515795] 'read index received' (duration: 144.613126ms)","trace[681515795] 'applied index is now lower than readState.Index' (duration: 149.124µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:01:44.565387Z","caller":"traceutil/trace.go:171","msg":"trace[919244864] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"149.914931ms","start":"2026-05-05T14:01:44.415439Z","end":"2026-05-05T14:01:44.565354Z","steps":["trace[919244864] 'process raft request' (duration: 149.630083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:44.565456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.901594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:01:44.565499Z","caller":"traceutil/trace.go:171","msg":"trace[151863546] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:8657; }","duration":"144.994467ms","start":"2026-05-05T14:01:44.420491Z","end":"2026-05-05T14:01:44.565486Z","steps":["trace[151863546] 'agreement among raft nodes before linearized reading' (duration: 144.899704ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:45.053082Z","caller":"traceutil/trace.go:171","msg":"trace[663664134] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"156.42949ms","start":"2026-05-05T14:01:44.896624Z","end":"2026-05-05T14:01:45.053053Z","steps":["trace[663664134] 'process raft request' (duration: 139.730612ms)","trace[663664134] 'compare' (duration: 16.557074ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T14:01:45.076205Z","caller":"traceutil/trace.go:171","msg":"trace[417698381] transaction","detail":"{read_only:false; response_revision:8664; number_of_response:1; }","duration":"171.857203ms","start":"2026-05-05T14:01:44.904323Z","end":"2026-05-05T14:01:45.07618Z","steps":["trace[417698381] 'process raft request' (duration: 171.703569ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:01:45.616186Z","caller":"traceutil/trace.go:171","msg":"trace[1045738617] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"183.898053ms","start":"2026-05-05T14:01:45.432265Z","end":"2026-05-05T14:01:45.616163Z","steps":["trace[1045738617] 'process raft request' (duration: 183.723108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:01:45.80422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.108624ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-05T14:01:45.804378Z","caller":"traceutil/trace.go:171","msg":"trace[910198411] linearizableReadLoop","detail":"{readStateIndex:8978; appliedIndex:8977; }","duration":"123.290381ms","start":"2026-05-05T14:01:45.681062Z","end":"2026-05-05T14:01:45.804353Z","steps":["trace[910198411] 'read index received' (duration: 2.942541ms)","trace[910198411] 'applied index is now lower than readState.Index' (duration: 120.34535ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T14:01:45.804603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.532488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-05T14:01:45.80466Z","caller":"traceutil/trace.go:171","msg":"trace[1804776776] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8666; }","duration":"123.62788ms","start":"2026-05-05T14:01:45.681012Z","end":"2026-05-05T14:01:45.80464Z","steps":["trace[1804776776] 'agreement among raft nodes before linearized reading' (duration: 123.404844ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:02:02.807736Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d5.wal"} {"level":"info","ts":"2026-05-05T14:03:38.816684Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"564d150b9adc490e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T14:03:38.822266Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T14:03:38.822399Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T14:05:46.83822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8256} {"level":"info","ts":"2026-05-05T14:05:46.923751Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8256,"took":"83.09585ms","hash":2248924073,"current-db-size-bytes":56930304,"current-db-size":"57 MB","current-db-size-in-use-bytes":24940544,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-05T14:05:46.923846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2248924073,"revision":8256,"compact-revision":6042} {"level":"warn","ts":"2026-05-05T14:08:42.236249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.467356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-05-05T14:08:42.236267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.126378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T14:08:42.236312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.190069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:08:42.236341Z","caller":"traceutil/trace.go:171","msg":"trace[938073179] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11853; }","duration":"206.22713ms","start":"2026-05-05T14:08:42.030095Z","end":"2026-05-05T14:08:42.236322Z","steps":["trace[938073179] 'range keys from in-memory index tree' (duration: 205.892931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:42.236253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.404875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:08:42.236378Z","caller":"traceutil/trace.go:171","msg":"trace[2017109630] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11853; }","duration":"206.59885ms","start":"2026-05-05T14:08:42.029772Z","end":"2026-05-05T14:08:42.236371Z","steps":["trace[2017109630] 'range keys from in-memory index tree' (duration: 206.305562ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:08:42.236376Z","caller":"traceutil/trace.go:171","msg":"trace[1361552340] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11853; }","duration":"206.360174ms","start":"2026-05-05T14:08:42.029998Z","end":"2026-05-05T14:08:42.236359Z","steps":["trace[1361552340] 'range keys from in-memory index tree' (duration: 205.935592ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:08:42.236335Z","caller":"traceutil/trace.go:171","msg":"trace[468482515] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11853; }","duration":"206.575249ms","start":"2026-05-05T14:08:42.029741Z","end":"2026-05-05T14:08:42.236316Z","steps":["trace[468482515] 'range keys from in-memory index tree' (duration: 206.337963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:42.236183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.305862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T14:08:42.236455Z","caller":"traceutil/trace.go:171","msg":"trace[2060196783] transaction","detail":"{read_only:false; response_revision:11854; number_of_response:1; }","duration":"150.313535ms","start":"2026-05-05T14:08:42.08611Z","end":"2026-05-05T14:08:42.236424Z","steps":["trace[2060196783] 'process raft request' (duration: 143.19529ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:08:42.236512Z","caller":"traceutil/trace.go:171","msg":"trace[539148998] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11853; }","duration":"206.684253ms","start":"2026-05-05T14:08:42.02982Z","end":"2026-05-05T14:08:42.236504Z","steps":["trace[539148998] 'range keys from in-memory index tree' (duration: 206.22209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:42.237149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.997946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-05T14:08:42.237213Z","caller":"traceutil/trace.go:171","msg":"trace[1946828980] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11853; }","duration":"206.059516ms","start":"2026-05-05T14:08:42.031137Z","end":"2026-05-05T14:08:42.237197Z","steps":["trace[1946828980] 'range keys from in-memory index tree' (duration: 204.79623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T14:08:43.436744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.608162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T14:08:43.436826Z","caller":"traceutil/trace.go:171","msg":"trace[727513262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11860; }","duration":"127.756027ms","start":"2026-05-05T14:08:43.30905Z","end":"2026-05-05T14:08:43.436806Z","steps":["trace[727513262] 'range keys from in-memory index tree' (duration: 127.440067ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T14:10:46.845169Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10479} {"level":"info","ts":"2026-05-05T14:10:46.925847Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10479,"took":"78.296238ms","hash":780599270,"current-db-size-bytes":56930304,"current-db-size":"57 MB","current-db-size-in-use-bytes":26009600,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T14:10:46.925928Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":780599270,"revision":10479,"compact-revision":8256} {"level":"info","ts":"2026-05-05T14:15:46.851597Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12704} {"level":"info","ts":"2026-05-05T14:15:46.929103Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12704,"took":"74.863453ms","hash":1036603298,"current-db-size-bytes":56930304,"current-db-size":"57 MB","current-db-size-in-use-bytes":27205632,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-05T14:15:46.929161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1036603298,"revision":12704,"compact-revision":10479}