{"level":"warn","ts":"2026-09-09T00:59:28.800128Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T00:59:28.800361Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.165: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://162.253.55.165:2380","--initial-cluster=instance=https://162.253.55.165:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.165:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.165: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-09-09T00:59:28.800478Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T00:59:28.800512Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.165:2380"]} {"level":"info","ts":"2026-09-09T00:59:28.80055Z","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-09-09T00:59:28.801317Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.165:2379"]} {"level":"info","ts":"2026-09-09T00:59:28.801455Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.165:2380"],"listen-peer-urls":["https://162.253.55.165:2380"],"advertise-client-urls":["https://162.253.55.165:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.165:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.165: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-09-09T00:59:28.806264Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.541291ms"} {"level":"info","ts":"2026-09-09T00:59:28.814607Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e9d471e5f5fb9faf","cluster-id":"fb4c33c289aeb291"} {"level":"info","ts":"2026-09-09T00:59:28.814747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T00:59:28.814784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became follower at term 0"} {"level":"info","ts":"2026-09-09T00:59:28.814821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e9d471e5f5fb9faf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T00:59:28.814845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became follower at term 1"} {"level":"info","ts":"2026-09-09T00:59:28.815036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf switched to configuration voters=(16849217338388225967)"} {"level":"warn","ts":"2026-09-09T00:59:28.823872Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T00:59:28.825314Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T00:59:28.826602Z","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-09-09T00:59:28.828681Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e9d471e5f5fb9faf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T00:59:28.829363Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e9d471e5f5fb9faf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T00:59:28.832958Z","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-09-09T00:59:28.833105Z","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-09-09T00:59:28.833155Z","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-09-09T00:59:28.833331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf switched to configuration voters=(16849217338388225967)"} {"level":"info","ts":"2026-09-09T00:59:28.833631Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb4c33c289aeb291","local-member-id":"e9d471e5f5fb9faf","added-peer-id":"e9d471e5f5fb9faf","added-peer-peer-urls":["https://162.253.55.165:2380"]} {"level":"info","ts":"2026-09-09T00:59:28.835875Z","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-09-09T00:59:28.836095Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.165:2380"} {"level":"info","ts":"2026-09-09T00:59:28.836173Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e9d471e5f5fb9faf","initial-advertise-peer-urls":["https://162.253.55.165:2380"],"listen-peer-urls":["https://162.253.55.165:2380"],"advertise-client-urls":["https://162.253.55.165:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.165:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T00:59:28.836246Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T00:59:28.836219Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.165:2380"} {"level":"info","ts":"2026-09-09T00:59:29.015591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T00:59:29.01565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T00:59:29.015667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf received MsgPreVoteResp from e9d471e5f5fb9faf at term 1"} {"level":"info","ts":"2026-09-09T00:59:29.01568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became candidate at term 2"} {"level":"info","ts":"2026-09-09T00:59:29.015688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf received MsgVoteResp from e9d471e5f5fb9faf at term 2"} {"level":"info","ts":"2026-09-09T00:59:29.015699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became leader at term 2"} {"level":"info","ts":"2026-09-09T00:59:29.015708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e9d471e5f5fb9faf elected leader e9d471e5f5fb9faf at term 2"} {"level":"info","ts":"2026-09-09T00:59:29.016519Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T00:59:29.017565Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T00:59:29.017568Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e9d471e5f5fb9faf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.165:2379]}","request-path":"/0/members/e9d471e5f5fb9faf/attributes","cluster-id":"fb4c33c289aeb291","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T00:59:29.019858Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.165:2379"} {"level":"info","ts":"2026-09-09T00:59:29.020039Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T00:59:29.020563Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T00:59:29.020584Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T00:59:29.022099Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T00:59:29.030958Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb4c33c289aeb291","local-member-id":"e9d471e5f5fb9faf","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T00:59:29.031039Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T00:59:29.031063Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T00:59:41.703792Z","caller":"traceutil/trace.go:171","msg":"trace[1762242245] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"205.366683ms","start":"2026-09-09T00:59:41.49839Z","end":"2026-09-09T00:59:41.703757Z","steps":["trace[1762242245] 'process raft request' (duration: 117.455671ms)","trace[1762242245] 'compare' (duration: 87.652009ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T00:59:42.717078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.588436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.165\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-09T00:59:42.717244Z","caller":"traceutil/trace.go:171","msg":"trace[1020150951] range","detail":"{range_begin:/registry/masterleases/162.253.55.165; range_end:; response_count:1; response_revision:403; }","duration":"148.817908ms","start":"2026-09-09T00:59:42.568384Z","end":"2026-09-09T00:59:42.717202Z","steps":["trace[1020150951] 'range keys from in-memory index tree' (duration: 148.451344ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T00:59:43.516932Z","caller":"traceutil/trace.go:171","msg":"trace[2037383050] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"138.044511ms","start":"2026-09-09T00:59:43.378865Z","end":"2026-09-09T00:59:43.516909Z","steps":["trace[2037383050] 'read index received' (duration: 137.818638ms)","trace[2037383050] 'applied index is now lower than readState.Index' (duration: 225.093µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T00:59:43.517082Z","caller":"traceutil/trace.go:171","msg":"trace[750527389] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"138.971899ms","start":"2026-09-09T00:59:43.378067Z","end":"2026-09-09T00:59:43.517039Z","steps":["trace[750527389] 'process raft request' (duration: 138.675196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T00:59:43.517265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.361204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-09-09T00:59:43.517349Z","caller":"traceutil/trace.go:171","msg":"trace[1034885449] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:408; }","duration":"138.493725ms","start":"2026-09-09T00:59:43.378835Z","end":"2026-09-09T00:59:43.517329Z","steps":["trace[1034885449] 'agreement among raft nodes before linearized reading' (duration: 138.212342ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T00:59:43.628537Z","caller":"traceutil/trace.go:171","msg":"trace[1806335853] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"100.644485ms","start":"2026-09-09T00:59:43.527854Z","end":"2026-09-09T00:59:43.628498Z","steps":["trace[1806335853] 'process raft request' (duration: 100.308282ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T00:59:44.537474Z","caller":"traceutil/trace.go:171","msg":"trace[290501783] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"115.045601ms","start":"2026-09-09T00:59:44.422402Z","end":"2026-09-09T00:59:44.537448Z","steps":["trace[290501783] 'process raft request' (duration: 80.365077ms)","trace[290501783] 'compare' (duration: 34.429922ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T00:59:44.863593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.283364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T00:59:44.863693Z","caller":"traceutil/trace.go:171","msg":"trace[84827677] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"294.439291ms","start":"2026-09-09T00:59:44.569239Z","end":"2026-09-09T00:59:44.863679Z","steps":["trace[84827677] 'process raft request' (duration: 183.485291ms)","trace[84827677] 'compare' (duration: 109.695458ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T00:59:45.200304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.989839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T00:59:45.200419Z","caller":"traceutil/trace.go:171","msg":"trace[1552753286] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"274.742094ms","start":"2026-09-09T00:59:44.925655Z","end":"2026-09-09T00:59:45.200397Z","steps":["trace[1552753286] 'process raft request' (duration: 46.564392ms)","trace[1552753286] 'compare' (duration: 227.590035ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T00:59:47.704422Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11506592060363813559,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-09T00:59:47.907838Z","caller":"traceutil/trace.go:171","msg":"trace[78917989] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"703.395791ms","start":"2026-09-09T00:59:47.204371Z","end":"2026-09-09T00:59:47.907767Z","steps":["trace[78917989] 'read index received' (duration: 703.19201ms)","trace[78917989] 'applied index is now lower than readState.Index' (duration: 202.191µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T00:59:47.907893Z","caller":"traceutil/trace.go:171","msg":"trace[934961264] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"976.857466ms","start":"2026-09-09T00:59:46.930982Z","end":"2026-09-09T00:59:47.90784Z","steps":["trace[934961264] 'process raft request' (duration: 976.554623ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T00:59:47.908076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.673324ms","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-09-09T00:59:47.908137Z","caller":"traceutil/trace.go:171","msg":"trace[1963604728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:419; }","duration":"703.794706ms","start":"2026-09-09T00:59:47.204325Z","end":"2026-09-09T00:59:47.90812Z","steps":["trace[1963604728] 'agreement among raft nodes before linearized reading' (duration: 703.652904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T00:59:47.908133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T00:59:46.930948Z","time spent":"977.057239ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T00:59:47.908205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T00:59:47.204301Z","time spent":"703.891006ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T00:59:48.002299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.621836ms","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-09-09T00:59:48.002399Z","caller":"traceutil/trace.go:171","msg":"trace[989126891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:420; }","duration":"441.812968ms","start":"2026-09-09T00:59:47.560564Z","end":"2026-09-09T00:59:48.002377Z","steps":["trace[989126891] 'agreement among raft nodes before linearized reading' (duration: 441.553045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T00:59:48.002436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T00:59:47.56054Z","time spent":"441.886708ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-09T01:00:14.39504Z","caller":"traceutil/trace.go:171","msg":"trace[1755935528] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"107.719965ms","start":"2026-09-09T01:00:14.287281Z","end":"2026-09-09T01:00:14.395001Z","steps":["trace[1755935528] 'process raft request' (duration: 107.440259ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.395264Z","caller":"traceutil/trace.go:171","msg":"trace[681728430] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"116.024498ms","start":"2026-09-09T01:00:14.279231Z","end":"2026-09-09T01:00:14.395256Z","steps":["trace[681728430] 'process raft request' (duration: 115.303977ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.39529Z","caller":"traceutil/trace.go:171","msg":"trace[1866916741] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"107.559196ms","start":"2026-09-09T01:00:14.2877Z","end":"2026-09-09T01:00:14.395259Z","steps":["trace[1866916741] 'process raft request' (duration: 107.222217ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.395586Z","caller":"traceutil/trace.go:171","msg":"trace[88395272] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"103.554788ms","start":"2026-09-09T01:00:14.292024Z","end":"2026-09-09T01:00:14.395579Z","steps":["trace[88395272] 'process raft request' (duration: 103.135064ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.395551Z","caller":"traceutil/trace.go:171","msg":"trace[1240221103] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"107.836412ms","start":"2026-09-09T01:00:14.287684Z","end":"2026-09-09T01:00:14.39552Z","steps":["trace[1240221103] 'process raft request' (duration: 107.148983ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.395722Z","caller":"traceutil/trace.go:171","msg":"trace[312688417] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"103.870647ms","start":"2026-09-09T01:00:14.291844Z","end":"2026-09-09T01:00:14.395714Z","steps":["trace[312688417] 'process raft request' (duration: 103.166057ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.395831Z","caller":"traceutil/trace.go:171","msg":"trace[819425841] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"103.25021ms","start":"2026-09-09T01:00:14.292575Z","end":"2026-09-09T01:00:14.395825Z","steps":["trace[819425841] 'process raft request' (duration: 102.836057ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.395895Z","caller":"traceutil/trace.go:171","msg":"trace[1288040740] transaction","detail":"{read_only:false; number_of_response:1; response_revision:557; }","duration":"103.370067ms","start":"2026-09-09T01:00:14.292517Z","end":"2026-09-09T01:00:14.395887Z","steps":["trace[1288040740] 'process raft request' (duration: 102.743822ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.395958Z","caller":"traceutil/trace.go:171","msg":"trace[1199086757] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"102.926332ms","start":"2026-09-09T01:00:14.293009Z","end":"2026-09-09T01:00:14.395935Z","steps":["trace[1199086757] 'process raft request' (duration: 102.486387ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.65854Z","caller":"traceutil/trace.go:171","msg":"trace[1566616699] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"224.351529ms","start":"2026-09-09T01:00:14.434162Z","end":"2026-09-09T01:00:14.658514Z","steps":["trace[1566616699] 'process raft request' (duration: 224.264884ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.658759Z","caller":"traceutil/trace.go:171","msg":"trace[628390717] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"237.237172ms","start":"2026-09-09T01:00:14.421473Z","end":"2026-09-09T01:00:14.65871Z","steps":["trace[628390717] 'process raft request' (duration: 140.11269ms)","trace[628390717] 'compare' (duration: 96.673166ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:14.658889Z","caller":"traceutil/trace.go:171","msg":"trace[1658232081] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:589; }","duration":"235.045518ms","start":"2026-09-09T01:00:14.423834Z","end":"2026-09-09T01:00:14.658879Z","steps":["trace[1658232081] 'read index received' (duration: 34.610752ms)","trace[1658232081] 'applied index is now lower than readState.Index' (duration: 200.434066ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:14.658942Z","caller":"traceutil/trace.go:171","msg":"trace[1905934816] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"215.671984ms","start":"2026-09-09T01:00:14.443247Z","end":"2026-09-09T01:00:14.658919Z","steps":["trace[1905934816] 'process raft request' (duration: 215.226089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:14.659058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.204947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-09-09T01:00:14.659033Z","caller":"traceutil/trace.go:171","msg":"trace[132811312] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"203.861941ms","start":"2026-09-09T01:00:14.455102Z","end":"2026-09-09T01:00:14.658964Z","steps":["trace[132811312] 'process raft request' (duration: 203.710162ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.659087Z","caller":"traceutil/trace.go:171","msg":"trace[653821216] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:570; }","duration":"235.288751ms","start":"2026-09-09T01:00:14.423788Z","end":"2026-09-09T01:00:14.659076Z","steps":["trace[653821216] 'agreement among raft nodes before linearized reading' (duration: 235.129872ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.659514Z","caller":"traceutil/trace.go:171","msg":"trace[229831929] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"198.92105ms","start":"2026-09-09T01:00:14.460578Z","end":"2026-09-09T01:00:14.659499Z","steps":["trace[229831929] 'process raft request' (duration: 198.516006ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.661255Z","caller":"traceutil/trace.go:171","msg":"trace[2042666436] transaction","detail":"{read_only:false; number_of_response:0; response_revision:571; }","duration":"193.037305ms","start":"2026-09-09T01:00:14.468158Z","end":"2026-09-09T01:00:14.661195Z","steps":["trace[2042666436] 'process raft request' (duration: 191.213671ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.665538Z","caller":"traceutil/trace.go:171","msg":"trace[45712607] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"169.571869ms","start":"2026-09-09T01:00:14.495941Z","end":"2026-09-09T01:00:14.665513Z","steps":["trace[45712607] 'process raft request' (duration: 163.480162ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.665801Z","caller":"traceutil/trace.go:171","msg":"trace[1980417609] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"166.769599ms","start":"2026-09-09T01:00:14.498977Z","end":"2026-09-09T01:00:14.665747Z","steps":["trace[1980417609] 'process raft request' (duration: 165.270533ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:15.041228Z","caller":"traceutil/trace.go:171","msg":"trace[2003896005] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"364.863317ms","start":"2026-09-09T01:00:14.676331Z","end":"2026-09-09T01:00:15.041194Z","steps":["trace[2003896005] 'process raft request' (duration: 354.374056ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:15.041411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:14.676302Z","time spent":"365.012855ms","remote":"127.0.0.1:42556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:00:15.043284Z","caller":"traceutil/trace.go:171","msg":"trace[2113109796] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"305.715577ms","start":"2026-09-09T01:00:14.737549Z","end":"2026-09-09T01:00:15.043265Z","steps":["trace[2113109796] 'process raft request' (duration: 305.564808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:15.043425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:14.737511Z","time spent":"305.851975ms","remote":"127.0.0.1:42556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T01:00:15.043575Z","caller":"traceutil/trace.go:171","msg":"trace[1957825267] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"361.418399ms","start":"2026-09-09T01:00:14.682146Z","end":"2026-09-09T01:00:15.043564Z","steps":["trace[1957825267] 'process raft request' (duration: 360.845876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:15.043625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:14.682119Z","time spent":"361.472662ms","remote":"127.0.0.1:42556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:00:15.04358Z","caller":"traceutil/trace.go:171","msg":"trace[1023134197] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:601; }","duration":"242.479355ms","start":"2026-09-09T01:00:14.801055Z","end":"2026-09-09T01:00:15.043534Z","steps":["trace[1023134197] 'read index received' (duration: 229.657843ms)","trace[1023134197] 'applied index is now lower than readState.Index' (duration: 12.819852ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:15.043967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.876657ms","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-09-09T01:00:15.044015Z","caller":"traceutil/trace.go:171","msg":"trace[346872573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"242.997184ms","start":"2026-09-09T01:00:14.801001Z","end":"2026-09-09T01:00:15.043999Z","steps":["trace[346872573] 'agreement among raft nodes before linearized reading' (duration: 242.693197ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:15.044244Z","caller":"traceutil/trace.go:171","msg":"trace[1771454687] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"291.551639ms","start":"2026-09-09T01:00:14.752684Z","end":"2026-09-09T01:00:15.044235Z","steps":["trace[1771454687] 'process raft request' (duration: 290.492058ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:15.629158Z","caller":"traceutil/trace.go:171","msg":"trace[1421928009] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"109.785409ms","start":"2026-09-09T01:00:15.519346Z","end":"2026-09-09T01:00:15.629132Z","steps":["trace[1421928009] 'read index received' (duration: 32.1636ms)","trace[1421928009] 'applied index is now lower than readState.Index' (duration: 77.620889ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:15.629306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.926307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:00:15.629324Z","caller":"traceutil/trace.go:171","msg":"trace[2140808401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"110.032984ms","start":"2026-09-09T01:00:15.519285Z","end":"2026-09-09T01:00:15.629318Z","steps":["trace[2140808401] 'agreement among raft nodes before linearized reading' (duration: 109.937778ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:15.629985Z","caller":"traceutil/trace.go:171","msg":"trace[474986791] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"117.034777ms","start":"2026-09-09T01:00:15.51294Z","end":"2026-09-09T01:00:15.629974Z","steps":["trace[474986791] 'process raft request' (duration: 38.700049ms)","trace[474986791] 'compare' (duration: 77.249338ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:21.321601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.660614ms","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-09-09T01:00:21.321912Z","caller":"traceutil/trace.go:171","msg":"trace[1282159226] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:646; }","duration":"155.989501ms","start":"2026-09-09T01:00:21.165892Z","end":"2026-09-09T01:00:21.321882Z","steps":["trace[1282159226] 'range keys from in-memory index tree' (duration: 155.635062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:21.322158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.36032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T01:00:21.32223Z","caller":"traceutil/trace.go:171","msg":"trace[2045315848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"209.479526ms","start":"2026-09-09T01:00:21.112732Z","end":"2026-09-09T01:00:21.322212Z","steps":["trace[2045315848] 'range keys from in-memory index tree' (duration: 209.16726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:21.322879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.871464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:00:21.32337Z","caller":"traceutil/trace.go:171","msg":"trace[1382494650] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"175.270891ms","start":"2026-09-09T01:00:21.148035Z","end":"2026-09-09T01:00:21.323305Z","steps":["trace[1382494650] 'process raft request' (duration: 14.618036ms)","trace[1382494650] 'compare' (duration: 159.065032ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:21.878916Z","caller":"traceutil/trace.go:171","msg":"trace[630435505] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"104.712203ms","start":"2026-09-09T01:00:21.774174Z","end":"2026-09-09T01:00:21.878887Z","steps":["trace[630435505] 'process raft request' (duration: 103.103048ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:22.076628Z","caller":"traceutil/trace.go:171","msg":"trace[585467268] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"108.308756ms","start":"2026-09-09T01:00:21.968294Z","end":"2026-09-09T01:00:22.076603Z","steps":["trace[585467268] 'process raft request' (duration: 108.097886ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:22.087758Z","caller":"traceutil/trace.go:171","msg":"trace[158118755] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"119.148596ms","start":"2026-09-09T01:00:21.968591Z","end":"2026-09-09T01:00:22.08774Z","steps":["trace[158118755] 'process raft request' (duration: 119.070872ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:22.877274Z","caller":"traceutil/trace.go:171","msg":"trace[53877306] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"306.521418ms","start":"2026-09-09T01:00:22.570721Z","end":"2026-09-09T01:00:22.877243Z","steps":["trace[53877306] 'read index received' (duration: 304.395198ms)","trace[53877306] 'applied index is now lower than readState.Index' (duration: 2.12514ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:22.877374Z","caller":"traceutil/trace.go:171","msg":"trace[2116975285] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"316.602131ms","start":"2026-09-09T01:00:22.560738Z","end":"2026-09-09T01:00:22.87734Z","steps":["trace[2116975285] 'process raft request' (duration: 314.812438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:22.877554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.810273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.165\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-09T01:00:22.877658Z","caller":"traceutil/trace.go:171","msg":"trace[1139825529] range","detail":"{range_begin:/registry/masterleases/162.253.55.165; range_end:; response_count:1; response_revision:657; }","duration":"306.964783ms","start":"2026-09-09T01:00:22.570676Z","end":"2026-09-09T01:00:22.877641Z","steps":["trace[1139825529] 'agreement among raft nodes before linearized reading' (duration: 306.691759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:22.877646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:22.560711Z","time spent":"316.795522ms","remote":"127.0.0.1:42620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:00:22.877597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.588072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-09-09T01:00:22.877737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:22.570655Z","time spent":"307.070118ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.165\" "} {"level":"info","ts":"2026-09-09T01:00:22.877791Z","caller":"traceutil/trace.go:171","msg":"trace[2128691439] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:657; }","duration":"219.870157ms","start":"2026-09-09T01:00:22.657883Z","end":"2026-09-09T01:00:22.877753Z","steps":["trace[2128691439] 'agreement among raft nodes before linearized reading' (duration: 219.563971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:23.505901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.261262ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-09-09T01:00:23.506125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:22.879515Z","time spent":"626.600701ms","remote":"127.0.0.1:42546","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-09T01:00:23.506212Z","caller":"traceutil/trace.go:171","msg":"trace[1454575407] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:686; }","duration":"620.647803ms","start":"2026-09-09T01:00:22.885538Z","end":"2026-09-09T01:00:23.506186Z","steps":["trace[1454575407] 'read index received' (duration: 65.951569ms)","trace[1454575407] 'applied index is now lower than readState.Index' (duration: 554.694414ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:23.506278Z","caller":"traceutil/trace.go:171","msg":"trace[810346149] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"624.718894ms","start":"2026-09-09T01:00:22.881487Z","end":"2026-09-09T01:00:23.506206Z","steps":["trace[810346149] 'process raft request' (duration: 624.551975ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:23.506558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:22.881453Z","time spent":"624.957097ms","remote":"127.0.0.1:42602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T01:00:23.506825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.311017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-09T01:00:23.506999Z","caller":"traceutil/trace.go:171","msg":"trace[1804055940] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:658; }","duration":"621.529498ms","start":"2026-09-09T01:00:22.885449Z","end":"2026-09-09T01:00:23.506979Z","steps":["trace[1804055940] 'agreement among raft nodes before linearized reading' (duration: 620.830252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:23.507713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:22.885429Z","time spent":"622.235304ms","remote":"127.0.0.1:42622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6555,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"warn","ts":"2026-09-09T01:00:24.904855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111003301s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:00:24.905292Z","caller":"traceutil/trace.go:171","msg":"trace[2106021630] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.396663588s","start":"2026-09-09T01:00:23.508615Z","end":"2026-09-09T01:00:24.905278Z","steps":["trace[2106021630] 'process raft request' (duration: 1.396607586s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:24.905346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:23.508589Z","time spent":"1.396723751s","remote":"127.0.0.1:42546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:00:24.905489Z","caller":"traceutil/trace.go:171","msg":"trace[362109498] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"1.399195387s","start":"2026-09-09T01:00:23.506243Z","end":"2026-09-09T01:00:24.905438Z","steps":["trace[362109498] 'read index received' (duration: 287.572596ms)","trace[362109498] 'applied index is now lower than readState.Index' (duration: 1.111617511s)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:24.905564Z","caller":"traceutil/trace.go:171","msg":"trace[1013255792] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.953429567s","start":"2026-09-09T01:00:22.951985Z","end":"2026-09-09T01:00:24.905415Z","steps":["trace[1013255792] 'process raft request' (duration: 841.806796ms)","trace[1013255792] 'compare' (duration: 1.110875914s)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:24.905684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410348939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tbqqj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-09T01:00:24.90576Z","caller":"traceutil/trace.go:171","msg":"trace[928706165] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tbqqj; range_end:; response_count:1; response_revision:660; }","duration":"1.410491376s","start":"2026-09-09T01:00:23.49525Z","end":"2026-09-09T01:00:24.905741Z","steps":["trace[928706165] 'agreement among raft nodes before linearized reading' (duration: 1.410206581s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:24.905814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:23.495231Z","time spent":"1.41057289s","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-tbqqj\" "} {"level":"warn","ts":"2026-09-09T01:00:24.90581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.551188036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-09T01:00:24.905836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.851534ms","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-09-09T01:00:24.905862Z","caller":"traceutil/trace.go:171","msg":"trace[1518322924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"613.916187ms","start":"2026-09-09T01:00:24.291939Z","end":"2026-09-09T01:00:24.905855Z","steps":["trace[1518322924] 'agreement among raft nodes before linearized reading' (duration: 613.818892ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:24.905899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:24.291916Z","time spent":"613.97809ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T01:00:24.905899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.513055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-09T01:00:24.905868Z","caller":"traceutil/trace.go:171","msg":"trace[849468961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:660; }","duration":"1.551296051s","start":"2026-09-09T01:00:23.354555Z","end":"2026-09-09T01:00:24.905851Z","steps":["trace[849468961] 'agreement among raft nodes before linearized reading' (duration: 1.5510762s)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:24.905922Z","caller":"traceutil/trace.go:171","msg":"trace[2136961587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"687.580888ms","start":"2026-09-09T01:00:24.218335Z","end":"2026-09-09T01:00:24.905916Z","steps":["trace[2136961587] 'agreement among raft nodes before linearized reading' (duration: 687.458332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:24.905955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:24.218319Z","time spent":"687.629411ms","remote":"127.0.0.1:42774","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-09-09T01:00:24.905962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:23.354532Z","time spent":"1.551416287s","remote":"127.0.0.1:42774","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-09-09T01:00:24.905766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:22.951963Z","time spent":"1.953720862s","remote":"127.0.0.1:42742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T01:00:24.906034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.560647ms","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-09-09T01:00:24.906054Z","caller":"traceutil/trace.go:171","msg":"trace[1195274527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"380.61353ms","start":"2026-09-09T01:00:24.525434Z","end":"2026-09-09T01:00:24.906048Z","steps":["trace[1195274527] 'agreement among raft nodes before linearized reading' (duration: 380.552457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:24.90607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:24.525412Z","time spent":"380.654542ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-09T01:00:24.90653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398008186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:00:24.906562Z","caller":"traceutil/trace.go:171","msg":"trace[422369181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"1.398084289s","start":"2026-09-09T01:00:23.508469Z","end":"2026-09-09T01:00:24.906553Z","steps":["trace[422369181] 'agreement among raft nodes before linearized reading' (duration: 1.397259348s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:24.906577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:23.508445Z","time spent":"1.398127931s","remote":"127.0.0.1:42514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-09T01:00:25.942698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.753198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:00:25.942808Z","caller":"traceutil/trace.go:171","msg":"trace[1621498348] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.031389832s","start":"2026-09-09T01:00:24.911398Z","end":"2026-09-09T01:00:25.942788Z","steps":["trace[1621498348] 'process raft request' (duration: 253.437394ms)","trace[1621498348] 'compare' (duration: 777.530657ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:25.942864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:24.911378Z","time spent":"1.031459446s","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:00:26.411383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.238713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:00:26.411667Z","caller":"traceutil/trace.go:171","msg":"trace[1180986010] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.499916193s","start":"2026-09-09T01:00:24.91173Z","end":"2026-09-09T01:00:26.411646Z","steps":["trace[1180986010] 'process raft request' (duration: 1.499765345s)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:26.411682Z","caller":"traceutil/trace.go:171","msg":"trace[344589578] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.499934204s","start":"2026-09-09T01:00:24.911685Z","end":"2026-09-09T01:00:26.41162Z","steps":["trace[344589578] 'process raft request' (duration: 1.15630572s)","trace[344589578] 'compare' (duration: 343.016662ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:26.411996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:24.911654Z","time spent":"1.500218258s","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:00:26.412007Z","caller":"traceutil/trace.go:171","msg":"trace[290019514] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:690; }","duration":"1.49788356s","start":"2026-09-09T01:00:24.914106Z","end":"2026-09-09T01:00:26.41199Z","steps":["trace[290019514] 'read index received' (duration: 250.740698ms)","trace[290019514] 'applied index is now lower than readState.Index' (duration: 1.247142172s)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:26.412022Z","caller":"traceutil/trace.go:171","msg":"trace[1276025071] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.491360219s","start":"2026-09-09T01:00:24.920644Z","end":"2026-09-09T01:00:26.412004Z","steps":["trace[1276025071] 'process raft request' (duration: 1.491217612s)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:26.412032Z","caller":"traceutil/trace.go:171","msg":"trace[656003332] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.500242469s","start":"2026-09-09T01:00:24.911771Z","end":"2026-09-09T01:00:26.412013Z","steps":["trace[656003332] 'process raft request' (duration: 1.499833689s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:26.412127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:24.920623Z","time spent":"1.491443203s","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:00:26.412126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:24.911762Z","time spent":"1.500310043s","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:00:26.412155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498033877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-09T01:00:26.412182Z","caller":"traceutil/trace.go:171","msg":"trace[1152598804] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:666; }","duration":"1.4980937s","start":"2026-09-09T01:00:24.914081Z","end":"2026-09-09T01:00:26.412175Z","steps":["trace[1152598804] 'agreement among raft nodes before linearized reading' (duration: 1.498007556s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:26.412202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:24.914069Z","time spent":"1.498127581s","remote":"127.0.0.1:42722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-09-09T01:00:26.412138Z","caller":"traceutil/trace.go:171","msg":"trace[1688153729] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"867.070999ms","start":"2026-09-09T01:00:25.545045Z","end":"2026-09-09T01:00:26.412116Z","steps":["trace[1688153729] 'process raft request' (duration: 866.917441ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:26.412329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:25.545004Z","time spent":"867.271109ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:00:26.412371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.907172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:00:26.411786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:24.911716Z","time spent":"1.500004767s","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:00:26.41242Z","caller":"traceutil/trace.go:171","msg":"trace[1983508896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"108.989496ms","start":"2026-09-09T01:00:26.303414Z","end":"2026-09-09T01:00:26.412404Z","steps":["trace[1983508896] 'agreement among raft nodes before linearized reading' (duration: 108.917913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:26.412478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494066134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:00:26.412393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498087429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-09T01:00:26.412528Z","caller":"traceutil/trace.go:171","msg":"trace[244923238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"1.494122317s","start":"2026-09-09T01:00:24.918384Z","end":"2026-09-09T01:00:26.412507Z","steps":["trace[244923238] 'agreement among raft nodes before linearized reading' (duration: 1.494059694s)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:26.412545Z","caller":"traceutil/trace.go:171","msg":"trace[1816652252] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:666; }","duration":"1.498272638s","start":"2026-09-09T01:00:24.914254Z","end":"2026-09-09T01:00:26.412527Z","steps":["trace[1816652252] 'agreement among raft nodes before linearized reading' (duration: 1.498036837s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:26.412568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:24.918374Z","time spent":"1.494186541s","remote":"127.0.0.1:42514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-09T01:00:26.412605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:24.914248Z","time spent":"1.498338761s","remote":"127.0.0.1:42790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-09-09T01:00:27.740595Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.310045387s","expected-duration":"1s"} {"level":"warn","ts":"2026-09-09T01:00:28.330148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.262364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:00:28.330313Z","caller":"traceutil/trace.go:171","msg":"trace[657859328] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.899836486s","start":"2026-09-09T01:00:26.430444Z","end":"2026-09-09T01:00:28.33028Z","steps":["trace[657859328] 'process raft request' (duration: 1.310393744s)","trace[657859328] 'compare' (duration: 589.089465ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:28.330339Z","caller":"traceutil/trace.go:171","msg":"trace[2030060437] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"824.429015ms","start":"2026-09-09T01:00:27.505891Z","end":"2026-09-09T01:00:28.33032Z","steps":["trace[2030060437] 'read index received' (duration: 234.883658ms)","trace[2030060437] 'applied index is now lower than readState.Index' (duration: 589.543067ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:28.330418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:26.430424Z","time spent":"1.89994846s","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T01:00:28.349575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.691717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:00:28.349671Z","caller":"traceutil/trace.go:171","msg":"trace[1775778013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"843.832694ms","start":"2026-09-09T01:00:27.505814Z","end":"2026-09-09T01:00:28.349647Z","steps":["trace[1775778013] 'agreement among raft nodes before linearized reading' (duration: 824.625444ms)","trace[1775778013] 'range keys from in-memory index tree' (duration: 19.083034ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:28.349716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:27.50579Z","time spent":"843.914798ms","remote":"127.0.0.1:42514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-09T01:00:28.349734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.510262ms","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-09-09T01:00:28.349784Z","caller":"traceutil/trace.go:171","msg":"trace[2121011111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:668; }","duration":"402.589135ms","start":"2026-09-09T01:00:27.947181Z","end":"2026-09-09T01:00:28.34977Z","steps":["trace[2121011111] 'agreement among raft nodes before linearized reading' (duration: 402.412997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:28.349831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:27.947164Z","time spent":"402.654938ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-09T01:00:29.749096Z","caller":"traceutil/trace.go:171","msg":"trace[2055456344] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"228.336548ms","start":"2026-09-09T01:00:29.520734Z","end":"2026-09-09T01:00:29.74907Z","steps":["trace[2055456344] 'process raft request' (duration: 228.152079ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:29.916736Z","caller":"traceutil/trace.go:171","msg":"trace[801768961] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"378.762925ms","start":"2026-09-09T01:00:29.537951Z","end":"2026-09-09T01:00:29.916714Z","steps":["trace[801768961] 'process raft request' (duration: 378.628339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:29.916861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:29.53791Z","time spent":"378.889021ms","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:00:46.069751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.139309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:00:46.069868Z","caller":"traceutil/trace.go:171","msg":"trace[805592420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"561.305156ms","start":"2026-09-09T01:00:45.508529Z","end":"2026-09-09T01:00:46.069834Z","steps":["trace[805592420] 'range keys from in-memory index tree' (duration: 560.979863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:46.069912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:45.508504Z","time spent":"561.397249ms","remote":"127.0.0.1:42514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-09T01:00:46.070161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.131726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tlcr6\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-09T01:00:46.070321Z","caller":"traceutil/trace.go:171","msg":"trace[10126590] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tlcr6; range_end:; response_count:1; response_revision:832; }","duration":"434.364665ms","start":"2026-09-09T01:00:45.635918Z","end":"2026-09-09T01:00:46.070283Z","steps":["trace[10126590] 'range keys from in-memory index tree' (duration: 433.865175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:46.070387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:45.635885Z","time spent":"434.485321ms","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tlcr6\" "} {"level":"warn","ts":"2026-09-09T01:01:05.006888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.265789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:01:05.007103Z","caller":"traceutil/trace.go:171","msg":"trace[468847314] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1060; }","duration":"155.249411ms","start":"2026-09-09T01:01:04.851834Z","end":"2026-09-09T01:01:05.007083Z","steps":["trace[468847314] 'read index received' (duration: 131.505259ms)","trace[468847314] 'applied index is now lower than readState.Index' (duration: 23.742892ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:05.007296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.456338ms","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-09-09T01:01:05.007383Z","caller":"traceutil/trace.go:171","msg":"trace[1782409343] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"179.570543ms","start":"2026-09-09T01:01:04.827798Z","end":"2026-09-09T01:01:05.007369Z","steps":["trace[1782409343] 'process raft request' (duration: 179.230061ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:05.007426Z","caller":"traceutil/trace.go:171","msg":"trace[1010016770] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"191.659497ms","start":"2026-09-09T01:01:04.815691Z","end":"2026-09-09T01:01:05.00735Z","steps":["trace[1010016770] 'process raft request' (duration: 27.819469ms)","trace[1010016770] 'compare' (duration: 163.077992ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:05.007597Z","caller":"traceutil/trace.go:171","msg":"trace[1535085249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"155.552821ms","start":"2026-09-09T01:01:04.851803Z","end":"2026-09-09T01:01:05.007356Z","steps":["trace[1535085249] 'agreement among raft nodes before linearized reading' (duration: 155.332384ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:05.647975Z","caller":"traceutil/trace.go:171","msg":"trace[1762224005] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"141.287542ms","start":"2026-09-09T01:01:05.506655Z","end":"2026-09-09T01:01:05.647943Z","steps":["trace[1762224005] 'read index received' (duration: 141.108916ms)","trace[1762224005] 'applied index is now lower than readState.Index' (duration: 177.686µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:05.648147Z","caller":"traceutil/trace.go:171","msg":"trace[505541666] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"160.864779ms","start":"2026-09-09T01:01:05.4872Z","end":"2026-09-09T01:01:05.648065Z","steps":["trace[505541666] 'process raft request' (duration: 160.601081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:05.648298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.583823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:05.648368Z","caller":"traceutil/trace.go:171","msg":"trace[1884427808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"141.732198ms","start":"2026-09-09T01:01:05.506617Z","end":"2026-09-09T01:01:05.648349Z","steps":["trace[1884427808] 'agreement among raft nodes before linearized reading' (duration: 141.478038ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:05.901234Z","caller":"traceutil/trace.go:171","msg":"trace[603063088] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"155.354818ms","start":"2026-09-09T01:01:05.745839Z","end":"2026-09-09T01:01:05.901193Z","steps":["trace[603063088] 'process raft request' (duration: 155.035908ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:06.492598Z","caller":"traceutil/trace.go:171","msg":"trace[1562419411] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"102.787779ms","start":"2026-09-09T01:01:06.389681Z","end":"2026-09-09T01:01:06.492469Z","steps":["trace[1562419411] 'process raft request' (duration: 102.476789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:06.695141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.461582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:01:06.69542Z","caller":"traceutil/trace.go:171","msg":"trace[741952109] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"191.114012ms","start":"2026-09-09T01:01:06.504253Z","end":"2026-09-09T01:01:06.695367Z","steps":["trace[741952109] 'process raft request' (duration: 90.34379ms)","trace[741952109] 'compare' (duration: 100.262506ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:06.695876Z","caller":"traceutil/trace.go:171","msg":"trace[1892829445] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1126; }","duration":"187.40057ms","start":"2026-09-09T01:01:06.508451Z","end":"2026-09-09T01:01:06.695852Z","steps":["trace[1892829445] 'read index received' (duration: 31.348407ms)","trace[1892829445] 'applied index is now lower than readState.Index' (duration: 156.050783ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:06.695935Z","caller":"traceutil/trace.go:171","msg":"trace[486018117] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"191.388441ms","start":"2026-09-09T01:01:06.504525Z","end":"2026-09-09T01:01:06.695913Z","steps":["trace[486018117] 'process raft request' (duration: 190.74226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:06.696127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.622028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:06.696189Z","caller":"traceutil/trace.go:171","msg":"trace[546487617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"187.790634ms","start":"2026-09-09T01:01:06.50838Z","end":"2026-09-09T01:01:06.696171Z","steps":["trace[546487617] 'agreement among raft nodes before linearized reading' (duration: 187.558385ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:06.696136Z","caller":"traceutil/trace.go:171","msg":"trace[461710180] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"167.374135ms","start":"2026-09-09T01:01:06.528682Z","end":"2026-09-09T01:01:06.696056Z","steps":["trace[461710180] 'process raft request' (duration: 167.000952ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:06.697394Z","caller":"traceutil/trace.go:171","msg":"trace[2089208199] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"155.569739ms","start":"2026-09-09T01:01:06.541791Z","end":"2026-09-09T01:01:06.697361Z","steps":["trace[2089208199] 'process raft request' (duration: 155.447225ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.032107Z","caller":"traceutil/trace.go:171","msg":"trace[1968779340] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"134.445086ms","start":"2026-09-09T01:01:06.897645Z","end":"2026-09-09T01:01:07.03209Z","steps":["trace[1968779340] 'process raft request' (duration: 130.737176ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.032437Z","caller":"traceutil/trace.go:171","msg":"trace[649282732] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"134.433667ms","start":"2026-09-09T01:01:06.897926Z","end":"2026-09-09T01:01:07.03236Z","steps":["trace[649282732] 'process raft request' (duration: 134.128046ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.032571Z","caller":"traceutil/trace.go:171","msg":"trace[292627764] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"133.048802ms","start":"2026-09-09T01:01:06.899505Z","end":"2026-09-09T01:01:07.032554Z","steps":["trace[292627764] 'process raft request' (duration: 132.761593ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.033062Z","caller":"traceutil/trace.go:171","msg":"trace[1192460492] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"133.492896ms","start":"2026-09-09T01:01:06.89956Z","end":"2026-09-09T01:01:07.033053Z","steps":["trace[1192460492] 'process raft request' (duration: 132.953359ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.033477Z","caller":"traceutil/trace.go:171","msg":"trace[507935593] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"132.748981ms","start":"2026-09-09T01:01:06.900711Z","end":"2026-09-09T01:01:07.03346Z","steps":["trace[507935593] 'process raft request' (duration: 132.301937ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.033502Z","caller":"traceutil/trace.go:171","msg":"trace[1486652478] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1150; }","duration":"122.388732ms","start":"2026-09-09T01:01:06.911083Z","end":"2026-09-09T01:01:07.033471Z","steps":["trace[1486652478] 'read index received' (duration: 117.340508ms)","trace[1486652478] 'applied index is now lower than readState.Index' (duration: 5.047244ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:07.033925Z","caller":"traceutil/trace.go:171","msg":"trace[2080868059] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"131.721548ms","start":"2026-09-09T01:01:06.901944Z","end":"2026-09-09T01:01:07.033665Z","steps":["trace[2080868059] 'process raft request' (duration: 131.369806ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.033878Z","caller":"traceutil/trace.go:171","msg":"trace[1209772237] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"131.712978ms","start":"2026-09-09T01:01:06.901952Z","end":"2026-09-09T01:01:07.033665Z","steps":["trace[1209772237] 'process raft request' (duration: 131.47275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.034787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.650242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:4640"} {"level":"warn","ts":"2026-09-09T01:01:07.034787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.595108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-09-09T01:01:07.034836Z","caller":"traceutil/trace.go:171","msg":"trace[542382499] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:1116; }","duration":"130.733504ms","start":"2026-09-09T01:01:06.90409Z","end":"2026-09-09T01:01:07.034824Z","steps":["trace[542382499] 'agreement among raft nodes before linearized reading' (duration: 129.613588ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.034848Z","caller":"traceutil/trace.go:171","msg":"trace[1730762390] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:1116; }","duration":"133.702791ms","start":"2026-09-09T01:01:06.901129Z","end":"2026-09-09T01:01:07.034832Z","steps":["trace[1730762390] 'agreement among raft nodes before linearized reading' (duration: 132.405409ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.059211Z","caller":"traceutil/trace.go:171","msg":"trace[2119306852] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"143.792165ms","start":"2026-09-09T01:01:06.915393Z","end":"2026-09-09T01:01:07.059186Z","steps":["trace[2119306852] 'process raft request' (duration: 143.399852ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.059455Z","caller":"traceutil/trace.go:171","msg":"trace[80603928] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"142.40817ms","start":"2026-09-09T01:01:06.916777Z","end":"2026-09-09T01:01:07.059185Z","steps":["trace[80603928] 'process raft request' (duration: 142.218374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.059601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.251298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:07.059655Z","caller":"traceutil/trace.go:171","msg":"trace[947681056] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck; range_end:; response_count:0; response_revision:1119; }","duration":"147.364881ms","start":"2026-09-09T01:01:06.912273Z","end":"2026-09-09T01:01:07.059638Z","steps":["trace[947681056] 'agreement among raft nodes before linearized reading' (duration: 147.260298ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.207837Z","caller":"traceutil/trace.go:171","msg":"trace[1340250217] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"170.60277ms","start":"2026-09-09T01:01:07.037182Z","end":"2026-09-09T01:01:07.207785Z","steps":["trace[1340250217] 'process raft request' (duration: 170.424904ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.208052Z","caller":"traceutil/trace.go:171","msg":"trace[869042967] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"163.955133ms","start":"2026-09-09T01:01:07.044072Z","end":"2026-09-09T01:01:07.208028Z","steps":["trace[869042967] 'process raft request' (duration: 163.707595ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.20812Z","caller":"traceutil/trace.go:171","msg":"trace[446420656] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"163.565741ms","start":"2026-09-09T01:01:07.044542Z","end":"2026-09-09T01:01:07.208107Z","steps":["trace[446420656] 'process raft request' (duration: 163.310632ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.208164Z","caller":"traceutil/trace.go:171","msg":"trace[92096930] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"169.524314ms","start":"2026-09-09T01:01:07.038625Z","end":"2026-09-09T01:01:07.208149Z","steps":["trace[92096930] 'process raft request' (duration: 169.10206ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.208248Z","caller":"traceutil/trace.go:171","msg":"trace[383427076] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"162.434515ms","start":"2026-09-09T01:01:07.045804Z","end":"2026-09-09T01:01:07.208238Z","steps":["trace[383427076] 'process raft request' (duration: 162.091274ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.208421Z","caller":"traceutil/trace.go:171","msg":"trace[440277973] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1161; }","duration":"149.231866ms","start":"2026-09-09T01:01:07.059156Z","end":"2026-09-09T01:01:07.208388Z","steps":["trace[440277973] 'read index received' (duration: 148.587775ms)","trace[440277973] 'applied index is now lower than readState.Index' (duration: 643.451µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:07.208584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.763286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-webhook-548949fc64-gc6tg\" ","response":"range_response_count:1 size:3838"} {"level":"info","ts":"2026-09-09T01:01:07.209552Z","caller":"traceutil/trace.go:171","msg":"trace[319147239] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-webhook-548949fc64-gc6tg; range_end:; response_count:1; response_revision:1125; }","duration":"168.76867ms","start":"2026-09-09T01:01:07.040768Z","end":"2026-09-09T01:01:07.209536Z","steps":["trace[319147239] 'agreement among raft nodes before linearized reading' (duration: 167.676753ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.208615Z","caller":"traceutil/trace.go:171","msg":"trace[184323894] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"161.847496ms","start":"2026-09-09T01:01:07.046759Z","end":"2026-09-09T01:01:07.208606Z","steps":["trace[184323894] 'process raft request' (duration: 161.432221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.208684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.191257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/cert-manager/cert-manager-75c4c745bc\" ","response":"range_response_count:1 size:4425"} {"level":"info","ts":"2026-09-09T01:01:07.209759Z","caller":"traceutil/trace.go:171","msg":"trace[142034001] range","detail":"{range_begin:/registry/replicasets/cert-manager/cert-manager-75c4c745bc; range_end:; response_count:1; response_revision:1125; }","duration":"167.284823ms","start":"2026-09-09T01:01:07.042469Z","end":"2026-09-09T01:01:07.209754Z","steps":["trace[142034001] 'agreement among raft nodes before linearized reading' (duration: 166.159666ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.28795Z","caller":"traceutil/trace.go:171","msg":"trace[1846201571] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"223.632402ms","start":"2026-09-09T01:01:07.064287Z","end":"2026-09-09T01:01:07.287919Z","steps":["trace[1846201571] 'process raft request' (duration: 223.416635ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.288232Z","caller":"traceutil/trace.go:171","msg":"trace[1196833730] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"222.95869ms","start":"2026-09-09T01:01:07.065258Z","end":"2026-09-09T01:01:07.288217Z","steps":["trace[1196833730] 'process raft request' (duration: 222.610819ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.28857Z","caller":"traceutil/trace.go:171","msg":"trace[341186011] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"125.611029ms","start":"2026-09-09T01:01:07.162945Z","end":"2026-09-09T01:01:07.288556Z","steps":["trace[341186011] 'process raft request' (duration: 125.051001ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.288938Z","caller":"traceutil/trace.go:171","msg":"trace[1639085919] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"223.656303ms","start":"2026-09-09T01:01:07.065261Z","end":"2026-09-09T01:01:07.288917Z","steps":["trace[1639085919] 'process raft request' (duration: 222.571908ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.390029Z","caller":"traceutil/trace.go:171","msg":"trace[1856781045] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"173.240755ms","start":"2026-09-09T01:01:07.216769Z","end":"2026-09-09T01:01:07.39001Z","steps":["trace[1856781045] 'process raft request' (duration: 173.148962ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.390043Z","caller":"traceutil/trace.go:171","msg":"trace[407733671] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"173.314908ms","start":"2026-09-09T01:01:07.216685Z","end":"2026-09-09T01:01:07.39Z","steps":["trace[407733671] 'process raft request' (duration: 134.761716ms)","trace[407733671] 'compare' (duration: 38.139309ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:07.39046Z","caller":"traceutil/trace.go:171","msg":"trace[1378050047] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"173.456582ms","start":"2026-09-09T01:01:07.216994Z","end":"2026-09-09T01:01:07.39045Z","steps":["trace[1378050047] 'process raft request' (duration: 172.957396ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.391181Z","caller":"traceutil/trace.go:171","msg":"trace[1997669262] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1170; }","duration":"102.746126ms","start":"2026-09-09T01:01:07.288418Z","end":"2026-09-09T01:01:07.391164Z","steps":["trace[1997669262] 'read index received' (duration: 63.047747ms)","trace[1997669262] 'applied index is now lower than readState.Index' (duration: 39.696039ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:07.39126Z","caller":"traceutil/trace.go:171","msg":"trace[483710274] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"167.592222ms","start":"2026-09-09T01:01:07.22366Z","end":"2026-09-09T01:01:07.391253Z","steps":["trace[483710274] 'process raft request' (duration: 167.342414ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.39144Z","caller":"traceutil/trace.go:171","msg":"trace[897661875] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"172.005136ms","start":"2026-09-09T01:01:07.219265Z","end":"2026-09-09T01:01:07.391271Z","steps":["trace[897661875] 'process raft request' (duration: 171.626404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.39187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.460762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-webhook-548949fc64-gc6tg\" ","response":"range_response_count:1 size:4791"} {"level":"info","ts":"2026-09-09T01:01:07.391912Z","caller":"traceutil/trace.go:171","msg":"trace[1217308046] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-webhook-548949fc64-gc6tg; range_end:; response_count:1; response_revision:1136; }","duration":"100.522844ms","start":"2026-09-09T01:01:07.291373Z","end":"2026-09-09T01:01:07.391896Z","steps":["trace[1217308046] 'agreement among raft nodes before linearized reading' (duration: 100.454232ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.391966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.676981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-2jjvs\" ","response":"range_response_count:1 size:2702"} {"level":"info","ts":"2026-09-09T01:01:07.392011Z","caller":"traceutil/trace.go:171","msg":"trace[1490711584] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-2jjvs; range_end:; response_count:1; response_revision:1136; }","duration":"101.762764ms","start":"2026-09-09T01:01:07.290233Z","end":"2026-09-09T01:01:07.391996Z","steps":["trace[1490711584] 'agreement among raft nodes before linearized reading' (duration: 101.553787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.392133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.649134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/cert-manager/cert-manager-75c4c745bc\" ","response":"range_response_count:1 size:4425"} {"level":"info","ts":"2026-09-09T01:01:07.392152Z","caller":"traceutil/trace.go:171","msg":"trace[1462957554] range","detail":"{range_begin:/registry/replicasets/cert-manager/cert-manager-75c4c745bc; range_end:; response_count:1; response_revision:1136; }","duration":"175.702726ms","start":"2026-09-09T01:01:07.216443Z","end":"2026-09-09T01:01:07.392146Z","steps":["trace[1462957554] 'agreement among raft nodes before linearized reading' (duration: 175.562631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.392782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.440886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-75c4c745bc-ntlp8\" ","response":"range_response_count:1 size:3544"} {"level":"info","ts":"2026-09-09T01:01:07.392914Z","caller":"traceutil/trace.go:171","msg":"trace[583951916] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-75c4c745bc-ntlp8; range_end:; response_count:1; response_revision:1136; }","duration":"102.57841ms","start":"2026-09-09T01:01:07.290289Z","end":"2026-09-09T01:01:07.392868Z","steps":["trace[583951916] 'agreement among raft nodes before linearized reading' (duration: 102.26411ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.989596Z","caller":"traceutil/trace.go:171","msg":"trace[179378601] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"158.538877ms","start":"2026-09-09T01:01:07.831025Z","end":"2026-09-09T01:01:07.989563Z","steps":["trace[179378601] 'process raft request' (duration: 158.155805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:08.2534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.920082ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-09T01:01:08.253726Z","caller":"traceutil/trace.go:171","msg":"trace[1454511870] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"104.09178ms","start":"2026-09-09T01:01:08.149614Z","end":"2026-09-09T01:01:08.253706Z","steps":["trace[1454511870] 'process raft request' (duration: 103.942396ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.253821Z","caller":"traceutil/trace.go:171","msg":"trace[1301432918] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1187; }","duration":"193.990913ms","start":"2026-09-09T01:01:08.059789Z","end":"2026-09-09T01:01:08.25378Z","steps":["trace[1301432918] 'read index received' (duration: 30.599625ms)","trace[1301432918] 'applied index is now lower than readState.Index' (duration: 163.385698ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:08.253935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.144128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:08.253972Z","caller":"traceutil/trace.go:171","msg":"trace[1072334321] range","detail":"{range_begin:/registry/jobs/cert-manager/cert-manager-startupapicheck; range_end:; response_count:0; response_revision:1147; }","duration":"194.2255ms","start":"2026-09-09T01:01:08.059732Z","end":"2026-09-09T01:01:08.253957Z","steps":["trace[1072334321] 'agreement among raft nodes before linearized reading' (duration: 194.144948ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.455009Z","caller":"traceutil/trace.go:171","msg":"trace[2133088568] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"190.252362ms","start":"2026-09-09T01:01:08.264725Z","end":"2026-09-09T01:01:08.454978Z","steps":["trace[2133088568] 'process raft request' (duration: 103.623165ms)","trace[2133088568] 'compare' (duration: 86.456041ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:08.477635Z","caller":"traceutil/trace.go:171","msg":"trace[833092449] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"126.086537ms","start":"2026-09-09T01:01:08.351524Z","end":"2026-09-09T01:01:08.47761Z","steps":["trace[833092449] 'process raft request' (duration: 125.836169ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.477682Z","caller":"traceutil/trace.go:171","msg":"trace[1576406263] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"208.146368ms","start":"2026-09-09T01:01:08.269521Z","end":"2026-09-09T01:01:08.477668Z","steps":["trace[1576406263] 'process raft request' (duration: 207.698273ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.599369Z","caller":"traceutil/trace.go:171","msg":"trace[849998659] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"135.930715ms","start":"2026-09-09T01:01:08.46335Z","end":"2026-09-09T01:01:08.599281Z","steps":["trace[849998659] 'process raft request' (duration: 135.869653ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.59962Z","caller":"traceutil/trace.go:171","msg":"trace[1663307642] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"137.359ms","start":"2026-09-09T01:01:08.462203Z","end":"2026-09-09T01:01:08.599562Z","steps":["trace[1663307642] 'process raft request' (duration: 108.554324ms)","trace[1663307642] 'compare' (duration: 28.279019ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:08.606987Z","caller":"traceutil/trace.go:171","msg":"trace[818722561] linearizableReadLoop","detail":"{readStateIndex:1195; appliedIndex:1192; }","duration":"101.073062ms","start":"2026-09-09T01:01:08.50589Z","end":"2026-09-09T01:01:08.606963Z","steps":["trace[818722561] 'read index received' (duration: 64.924769ms)","trace[818722561] 'applied index is now lower than readState.Index' (duration: 36.146873ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:08.607157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.242528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:08.607248Z","caller":"traceutil/trace.go:171","msg":"trace[1650278348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1153; }","duration":"101.352361ms","start":"2026-09-09T01:01:08.505883Z","end":"2026-09-09T01:01:08.607236Z","steps":["trace[1650278348] 'agreement among raft nodes before linearized reading' (duration: 101.169065ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.607612Z","caller":"traceutil/trace.go:171","msg":"trace[499359557] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"101.867998ms","start":"2026-09-09T01:01:08.50573Z","end":"2026-09-09T01:01:08.607598Z","steps":["trace[499359557] 'process raft request' (duration: 101.120744ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.784029Z","caller":"traceutil/trace.go:171","msg":"trace[1206801623] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"170.948991ms","start":"2026-09-09T01:01:08.613058Z","end":"2026-09-09T01:01:08.784007Z","steps":["trace[1206801623] 'process raft request' (duration: 170.887529ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.784038Z","caller":"traceutil/trace.go:171","msg":"trace[1408725712] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"171.113706ms","start":"2026-09-09T01:01:08.61289Z","end":"2026-09-09T01:01:08.784003Z","steps":["trace[1408725712] 'process raft request' (duration: 110.005559ms)","trace[1408725712] 'compare' (duration: 60.805057ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:08.979046Z","caller":"traceutil/trace.go:171","msg":"trace[1696887531] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"188.584179ms","start":"2026-09-09T01:01:08.790433Z","end":"2026-09-09T01:01:08.979017Z","steps":["trace[1696887531] 'process raft request' (duration: 188.461105ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.979102Z","caller":"traceutil/trace.go:171","msg":"trace[797039478] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"188.719703ms","start":"2026-09-09T01:01:08.79034Z","end":"2026-09-09T01:01:08.97906Z","steps":["trace[797039478] 'process raft request' (duration: 164.164824ms)","trace[797039478] 'compare' (duration: 24.204248ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:09.050339Z","caller":"traceutil/trace.go:171","msg":"trace[932861932] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1198; }","duration":"232.844349ms","start":"2026-09-09T01:01:08.817466Z","end":"2026-09-09T01:01:09.05031Z","steps":["trace[932861932] 'read index received' (duration: 137.026069ms)","trace[932861932] 'applied index is now lower than readState.Index' (duration: 95.81674ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:09.050395Z","caller":"traceutil/trace.go:171","msg":"trace[641294588] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"233.795421ms","start":"2026-09-09T01:01:08.816571Z","end":"2026-09-09T01:01:09.050366Z","steps":["trace[641294588] 'process raft request' (duration: 233.619045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:09.050954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.753538ms","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-09-09T01:01:09.051008Z","caller":"traceutil/trace.go:171","msg":"trace[1314718992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1159; }","duration":"201.856082ms","start":"2026-09-09T01:01:08.849138Z","end":"2026-09-09T01:01:09.050994Z","steps":["trace[1314718992] 'agreement among raft nodes before linearized reading' (duration: 201.414488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:09.050926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.428757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-startupapicheck-d9vbt\" ","response":"range_response_count:1 size:2916"} {"level":"info","ts":"2026-09-09T01:01:09.051135Z","caller":"traceutil/trace.go:171","msg":"trace[801533418] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-startupapicheck-d9vbt; range_end:; response_count:1; response_revision:1159; }","duration":"233.673025ms","start":"2026-09-09T01:01:08.81744Z","end":"2026-09-09T01:01:09.051113Z","steps":["trace[801533418] 'agreement among raft nodes before linearized reading' (duration: 232.994654ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:17.59805Z","caller":"traceutil/trace.go:171","msg":"trace[852575044] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"132.769228ms","start":"2026-09-09T01:01:17.465254Z","end":"2026-09-09T01:01:17.598023Z","steps":["trace[852575044] 'process raft request' (duration: 132.546951ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:17.624622Z","caller":"traceutil/trace.go:171","msg":"trace[1200518290] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"158.484033ms","start":"2026-09-09T01:01:17.466001Z","end":"2026-09-09T01:01:17.624485Z","steps":["trace[1200518290] 'process raft request' (duration: 158.219055ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:17.624802Z","caller":"traceutil/trace.go:171","msg":"trace[885778888] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1256; }","duration":"116.573297ms","start":"2026-09-09T01:01:17.508209Z","end":"2026-09-09T01:01:17.624782Z","steps":["trace[885778888] 'read index received' (duration: 89.643966ms)","trace[885778888] 'applied index is now lower than readState.Index' (duration: 26.927951ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:17.624905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.67848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:17.624944Z","caller":"traceutil/trace.go:171","msg":"trace[121151054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"116.779662ms","start":"2026-09-09T01:01:17.508151Z","end":"2026-09-09T01:01:17.624931Z","steps":["trace[121151054] 'agreement among raft nodes before linearized reading' (duration: 116.70371ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:17.62493Z","caller":"traceutil/trace.go:171","msg":"trace[526289917] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"150.540397ms","start":"2026-09-09T01:01:17.474368Z","end":"2026-09-09T01:01:17.624909Z","steps":["trace[526289917] 'process raft request' (duration: 150.042342ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:17.797157Z","caller":"traceutil/trace.go:171","msg":"trace[544749282] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"144.786515ms","start":"2026-09-09T01:01:17.652338Z","end":"2026-09-09T01:01:17.797124Z","steps":["trace[544749282] 'process raft request' (duration: 144.595049ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:17.963191Z","caller":"traceutil/trace.go:171","msg":"trace[137143178] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1260; }","duration":"148.189736ms","start":"2026-09-09T01:01:17.814975Z","end":"2026-09-09T01:01:17.963164Z","steps":["trace[137143178] 'read index received' (duration: 39.196885ms)","trace[137143178] 'applied index is now lower than readState.Index' (duration: 108.991701ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:17.963705Z","caller":"traceutil/trace.go:171","msg":"trace[301640287] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"158.860734ms","start":"2026-09-09T01:01:17.804816Z","end":"2026-09-09T01:01:17.963677Z","steps":["trace[301640287] 'process raft request' (duration: 158.288656ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:17.963774Z","caller":"traceutil/trace.go:171","msg":"trace[610724509] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"159.329637ms","start":"2026-09-09T01:01:17.804401Z","end":"2026-09-09T01:01:17.963731Z","steps":["trace[610724509] 'process raft request' (duration: 158.577064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:17.963783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.773744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-09T01:01:17.963893Z","caller":"traceutil/trace.go:171","msg":"trace[1384165149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1220; }","duration":"148.928638ms","start":"2026-09-09T01:01:17.814942Z","end":"2026-09-09T01:01:17.96387Z","steps":["trace[1384165149] 'agreement among raft nodes before linearized reading' (duration: 148.639809ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:18.12216Z","caller":"traceutil/trace.go:171","msg":"trace[539210912] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"147.203347ms","start":"2026-09-09T01:01:17.974903Z","end":"2026-09-09T01:01:18.122106Z","steps":["trace[539210912] 'process raft request' (duration: 146.3052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:27.524921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.237849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:01:27.525054Z","caller":"traceutil/trace.go:171","msg":"trace[941206947] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"262.934774ms","start":"2026-09-09T01:01:27.262093Z","end":"2026-09-09T01:01:27.525027Z","steps":["trace[941206947] 'read index received' (duration: 132.446669ms)","trace[941206947] 'applied index is now lower than readState.Index' (duration: 130.486355ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:27.525176Z","caller":"traceutil/trace.go:171","msg":"trace[1135717948] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"272.915728ms","start":"2026-09-09T01:01:27.252244Z","end":"2026-09-09T01:01:27.52516Z","steps":["trace[1135717948] 'process raft request' (duration: 142.324389ms)","trace[1135717948] 'compare' (duration: 129.837418ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:27.525464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.344225ms","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-09-09T01:01:27.525522Z","caller":"traceutil/trace.go:171","msg":"trace[125224793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1269; }","duration":"263.466618ms","start":"2026-09-09T01:01:27.262041Z","end":"2026-09-09T01:01:27.525508Z","steps":["trace[125224793] 'agreement among raft nodes before linearized reading' (duration: 263.272733ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:27.525706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.296545ms","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-09-09T01:01:27.525754Z","caller":"traceutil/trace.go:171","msg":"trace[1384970586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1269; }","duration":"101.344426ms","start":"2026-09-09T01:01:27.424396Z","end":"2026-09-09T01:01:27.525741Z","steps":["trace[1384970586] 'agreement among raft nodes before linearized reading' (duration: 101.228663ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:28.146076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11506592060363815274,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-09T01:01:28.267619Z","caller":"traceutil/trace.go:171","msg":"trace[1211066062] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"735.778853ms","start":"2026-09-09T01:01:27.531812Z","end":"2026-09-09T01:01:28.26759Z","steps":["trace[1211066062] 'process raft request' (duration: 695.767906ms)","trace[1211066062] 'compare' (duration: 39.406231ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:28.267764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:27.531775Z","time spent":"735.911407ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:01:28.333285Z","caller":"traceutil/trace.go:171","msg":"trace[326400580] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1315; }","duration":"688.04168ms","start":"2026-09-09T01:01:27.645218Z","end":"2026-09-09T01:01:28.333259Z","steps":["trace[326400580] 'read index received' (duration: 582.303826ms)","trace[326400580] 'applied index is now lower than readState.Index' (duration: 105.736674ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:28.333372Z","caller":"traceutil/trace.go:171","msg":"trace[1781015636] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"549.766992ms","start":"2026-09-09T01:01:27.783572Z","end":"2026-09-09T01:01:28.333339Z","steps":["trace[1781015636] 'process raft request' (duration: 549.506015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:28.333532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:27.783541Z","time spent":"549.910316ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:01:28.333663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.41391ms","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-09-09T01:01:28.333784Z","caller":"traceutil/trace.go:171","msg":"trace[1997586460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1272; }","duration":"688.583484ms","start":"2026-09-09T01:01:27.645174Z","end":"2026-09-09T01:01:28.333758Z","steps":["trace[1997586460] 'agreement among raft nodes before linearized reading' (duration: 688.315957ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:28.333834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:27.645141Z","time spent":"688.680047ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-09T01:01:28.334426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.813746ms","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-09-09T01:01:28.334618Z","caller":"traceutil/trace.go:171","msg":"trace[831993214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1272; }","duration":"664.039062ms","start":"2026-09-09T01:01:27.670561Z","end":"2026-09-09T01:01:28.3346Z","steps":["trace[831993214] 'agreement among raft nodes before linearized reading' (duration: 663.774105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:28.334997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:27.670537Z","time spent":"664.119025ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-09T01:01:28.335023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.639879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-09T01:01:28.335134Z","caller":"traceutil/trace.go:171","msg":"trace[1848297928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1272; }","duration":"110.845704ms","start":"2026-09-09T01:01:28.224271Z","end":"2026-09-09T01:01:28.335117Z","steps":["trace[1848297928] 'agreement among raft nodes before linearized reading' (duration: 110.111574ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:28.552894Z","caller":"traceutil/trace.go:171","msg":"trace[2055973439] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"210.671367ms","start":"2026-09-09T01:01:28.342198Z","end":"2026-09-09T01:01:28.552869Z","steps":["trace[2055973439] 'process raft request' (duration: 210.601695ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:28.55291Z","caller":"traceutil/trace.go:171","msg":"trace[590426116] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"212.696872ms","start":"2026-09-09T01:01:28.340169Z","end":"2026-09-09T01:01:28.552866Z","steps":["trace[590426116] 'process raft request' (duration: 211.101408ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:28.552982Z","caller":"traceutil/trace.go:171","msg":"trace[402307452] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"211.893301ms","start":"2026-09-09T01:01:28.341067Z","end":"2026-09-09T01:01:28.55296Z","steps":["trace[402307452] 'process raft request' (duration: 211.680815ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:30.306894Z","caller":"traceutil/trace.go:171","msg":"trace[1095139692] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"291.196064ms","start":"2026-09-09T01:01:30.015673Z","end":"2026-09-09T01:01:30.306869Z","steps":["trace[1095139692] 'read index received' (duration: 290.985568ms)","trace[1095139692] 'applied index is now lower than readState.Index' (duration: 209.086µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:30.306969Z","caller":"traceutil/trace.go:171","msg":"trace[659705927] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"408.452704ms","start":"2026-09-09T01:01:29.898474Z","end":"2026-09-09T01:01:30.306927Z","steps":["trace[659705927] 'process raft request' (duration: 408.200357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:30.307242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:29.898422Z","time spent":"408.6979ms","remote":"127.0.0.1:42742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3148,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:01:30.307423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.700868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:01:30.307561Z","caller":"traceutil/trace.go:171","msg":"trace[1448453543] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1280; }","duration":"291.908363ms","start":"2026-09-09T01:01:30.015619Z","end":"2026-09-09T01:01:30.307527Z","steps":["trace[1448453543] 'agreement among raft nodes before linearized reading' (duration: 291.40969ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:30.457805Z","caller":"traceutil/trace.go:171","msg":"trace[373775326] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"145.847454ms","start":"2026-09-09T01:01:30.311926Z","end":"2026-09-09T01:01:30.457774Z","steps":["trace[373775326] 'process raft request' (duration: 73.541578ms)","trace[373775326] 'compare' (duration: 72.110731ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:30.996053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.906031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:01:30.996376Z","caller":"traceutil/trace.go:171","msg":"trace[518098915] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1327; }","duration":"488.166376ms","start":"2026-09-09T01:01:30.508182Z","end":"2026-09-09T01:01:30.996349Z","steps":["trace[518098915] 'read index received' (duration: 256.70283ms)","trace[518098915] 'applied index is now lower than readState.Index' (duration: 231.462096ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:30.996596Z","caller":"traceutil/trace.go:171","msg":"trace[629633018] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"533.729446ms","start":"2026-09-09T01:01:30.462847Z","end":"2026-09-09T01:01:30.996576Z","steps":["trace[629633018] 'process raft request' (duration: 533.386377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:30.996712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:30.462816Z","time spent":"533.833039ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:01:30.99673Z","caller":"traceutil/trace.go:171","msg":"trace[365137641] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1282; }","duration":"536.15453ms","start":"2026-09-09T01:01:30.46055Z","end":"2026-09-09T01:01:30.996704Z","steps":["trace[365137641] 'process raft request' (duration: 304.310154ms)","trace[365137641] 'compare' (duration: 230.668895ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:30.996736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.526415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:30.996817Z","caller":"traceutil/trace.go:171","msg":"trace[694041998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"488.654928ms","start":"2026-09-09T01:01:30.508139Z","end":"2026-09-09T01:01:30.996794Z","steps":["trace[694041998] 'agreement among raft nodes before linearized reading' (duration: 488.515505ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:30.996834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:30.46052Z","time spent":"536.258983ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":87,"response count":0,"response size":42,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-09-09T01:01:30.996869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:30.508121Z","time spent":"488.739721ms","remote":"127.0.0.1:42514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-09T01:01:30.996898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.333149ms","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-09-09T01:01:30.996965Z","caller":"traceutil/trace.go:171","msg":"trace[791101392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1283; }","duration":"439.412981ms","start":"2026-09-09T01:01:30.557528Z","end":"2026-09-09T01:01:30.996941Z","steps":["trace[791101392] 'agreement among raft nodes before linearized reading' (duration: 439.179775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:30.996987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.711409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-startupapicheck-d9vbt\" ","response":"range_response_count:1 size:4162"} {"level":"warn","ts":"2026-09-09T01:01:30.996967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.178701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-09T01:01:30.997073Z","caller":"traceutil/trace.go:171","msg":"trace[34564416] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-startupapicheck-d9vbt; range_end:; response_count:1; response_revision:1283; }","duration":"130.880383ms","start":"2026-09-09T01:01:30.866143Z","end":"2026-09-09T01:01:30.997024Z","steps":["trace[34564416] 'agreement among raft nodes before linearized reading' (duration: 130.555945ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:30.997087Z","caller":"traceutil/trace.go:171","msg":"trace[1157722718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1283; }","duration":"440.325316ms","start":"2026-09-09T01:01:30.556743Z","end":"2026-09-09T01:01:30.997068Z","steps":["trace[1157722718] 'agreement among raft nodes before linearized reading' (duration: 439.924235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:30.997199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:30.556727Z","time spent":"440.450388ms","remote":"127.0.0.1:42774","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-09-09T01:01:30.997028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:30.55752Z","time spent":"439.494523ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-09T01:01:31.438813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.488907ms","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-09-09T01:01:31.438961Z","caller":"traceutil/trace.go:171","msg":"trace[219468601] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1285; }","duration":"272.661831ms","start":"2026-09-09T01:01:31.166278Z","end":"2026-09-09T01:01:31.43894Z","steps":["trace[219468601] 'range keys from in-memory index tree' (duration: 272.465036ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:31.439663Z","caller":"traceutil/trace.go:171","msg":"trace[1452859370] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"432.842457ms","start":"2026-09-09T01:01:31.006794Z","end":"2026-09-09T01:01:31.439636Z","steps":["trace[1452859370] 'process raft request' (duration: 395.336831ms)","trace[1452859370] 'compare' (duration: 36.640253ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:31.43983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:31.006779Z","time spent":"432.941019ms","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4149,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:01:49.399093Z","caller":"traceutil/trace.go:171","msg":"trace[877669738] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"124.43434ms","start":"2026-09-09T01:01:49.274624Z","end":"2026-09-09T01:01:49.399058Z","steps":["trace[877669738] 'process raft request' (duration: 91.072429ms)","trace[877669738] 'compare' (duration: 33.137875ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:49.399165Z","caller":"traceutil/trace.go:171","msg":"trace[1155019404] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"124.410439ms","start":"2026-09-09T01:01:49.274702Z","end":"2026-09-09T01:01:49.399113Z","steps":["trace[1155019404] 'process raft request' (duration: 124.270336ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:49.399547Z","caller":"traceutil/trace.go:171","msg":"trace[2002803884] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"124.823248ms","start":"2026-09-09T01:01:49.274687Z","end":"2026-09-09T01:01:49.39951Z","steps":["trace[2002803884] 'process raft request' (duration: 124.334147ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:49.399651Z","caller":"traceutil/trace.go:171","msg":"trace[1543623451] linearizableReadLoop","detail":"{readStateIndex:1546; appliedIndex:1540; }","duration":"115.856479ms","start":"2026-09-09T01:01:49.283779Z","end":"2026-09-09T01:01:49.399635Z","steps":["trace[1543623451] 'read index received' (duration: 82.10451ms)","trace[1543623451] 'applied index is now lower than readState.Index' (duration: 33.751199ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:49.399636Z","caller":"traceutil/trace.go:171","msg":"trace[1595477307] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"124.790947ms","start":"2026-09-09T01:01:49.274772Z","end":"2026-09-09T01:01:49.399563Z","steps":["trace[1595477307] 'process raft request' (duration: 124.562262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:49.399807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.009112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-09T01:01:49.399846Z","caller":"traceutil/trace.go:171","msg":"trace[1410765123] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1491; }","duration":"116.120895ms","start":"2026-09-09T01:01:49.283711Z","end":"2026-09-09T01:01:49.399832Z","steps":["trace[1410765123] 'agreement among raft nodes before linearized reading' (duration: 115.974452ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:49.399898Z","caller":"traceutil/trace.go:171","msg":"trace[836282849] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"125.034424ms","start":"2026-09-09T01:01:49.274852Z","end":"2026-09-09T01:01:49.399887Z","steps":["trace[836282849] 'process raft request' (duration: 124.638724ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:49.400102Z","caller":"traceutil/trace.go:171","msg":"trace[833398740] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"125.186396ms","start":"2026-09-09T01:01:49.274901Z","end":"2026-09-09T01:01:49.400087Z","steps":["trace[833398740] 'process raft request' (duration: 124.636514ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:49.404759Z","caller":"traceutil/trace.go:171","msg":"trace[1711132113] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"120.946558ms","start":"2026-09-09T01:01:49.283792Z","end":"2026-09-09T01:01:49.404738Z","steps":["trace[1711132113] 'process raft request' (duration: 120.506688ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:49.40499Z","caller":"traceutil/trace.go:171","msg":"trace[1437055786] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"119.404822ms","start":"2026-09-09T01:01:49.28558Z","end":"2026-09-09T01:01:49.404985Z","steps":["trace[1437055786] 'process raft request' (duration: 118.87817ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:49.405189Z","caller":"traceutil/trace.go:171","msg":"trace[99086883] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"112.746436ms","start":"2026-09-09T01:01:49.292438Z","end":"2026-09-09T01:01:49.405185Z","steps":["trace[99086883] 'process raft request' (duration: 112.071481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:19.396984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.836481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:02:19.397066Z","caller":"traceutil/trace.go:171","msg":"trace[780812508] linearizableReadLoop","detail":"{readStateIndex:2031; appliedIndex:2030; }","duration":"451.818572ms","start":"2026-09-09T01:02:18.945236Z","end":"2026-09-09T01:02:19.397054Z","steps":["trace[780812508] 'read index received' (duration: 240.764577ms)","trace[780812508] 'applied index is now lower than readState.Index' (duration: 211.052775ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:02:19.397131Z","caller":"traceutil/trace.go:171","msg":"trace[1825571834] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"534.568572ms","start":"2026-09-09T01:02:18.862555Z","end":"2026-09-09T01:02:19.397123Z","steps":["trace[1825571834] 'process raft request' (duration: 323.524817ms)","trace[1825571834] 'compare' (duration: 210.750029ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:02:19.397219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:18.862442Z","time spent":"534.708565ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:02:19.397325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.595133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f9a830c-001a-4663-ae4d-f14d62d4803b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-09T01:02:19.397408Z","caller":"traceutil/trace.go:171","msg":"trace[1753925096] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4f9a830c-001a-4663-ae4d-f14d62d4803b; range_end:; response_count:1; response_revision:1948; }","duration":"293.722056ms","start":"2026-09-09T01:02:19.103668Z","end":"2026-09-09T01:02:19.39739Z","steps":["trace[1753925096] 'agreement among raft nodes before linearized reading' (duration: 293.526951ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:19.397712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.486896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:02:19.397757Z","caller":"traceutil/trace.go:171","msg":"trace[815719138] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1948; }","duration":"452.588248ms","start":"2026-09-09T01:02:18.945146Z","end":"2026-09-09T01:02:19.397734Z","steps":["trace[815719138] 'agreement among raft nodes before linearized reading' (duration: 452.532547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:19.397782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:18.945123Z","time spent":"452.652079ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-09T01:02:19.397828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.173775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T01:02:19.39788Z","caller":"traceutil/trace.go:171","msg":"trace[328595449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1948; }","duration":"143.266807ms","start":"2026-09-09T01:02:19.254601Z","end":"2026-09-09T01:02:19.397868Z","steps":["trace[328595449] 'agreement among raft nodes before linearized reading' (duration: 143.147045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:19.905408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.939098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:02:19.905724Z","caller":"traceutil/trace.go:171","msg":"trace[1452092682] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"142.220827ms","start":"2026-09-09T01:02:19.763481Z","end":"2026-09-09T01:02:19.905702Z","steps":["trace[1452092682] 'process raft request' (duration: 142.171436ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:19.905749Z","caller":"traceutil/trace.go:171","msg":"trace[397252383] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"501.322984ms","start":"2026-09-09T01:02:19.404395Z","end":"2026-09-09T01:02:19.905718Z","steps":["trace[397252383] 'process raft request' (duration: 501.14188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:19.906015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:19.404382Z","time spent":"501.554128ms","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:02:19.90613Z","caller":"traceutil/trace.go:171","msg":"trace[1156869432] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"501.543298ms","start":"2026-09-09T01:02:19.404571Z","end":"2026-09-09T01:02:19.906114Z","steps":["trace[1156869432] 'process raft request' (duration: 501.037818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:19.906252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:19.404558Z","time spent":"501.64468ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:02:19.906437Z","caller":"traceutil/trace.go:171","msg":"trace[1753226830] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"502.513036ms","start":"2026-09-09T01:02:19.403908Z","end":"2026-09-09T01:02:19.906421Z","steps":["trace[1753226830] 'process raft request' (duration: 232.476088ms)","trace[1753226830] 'compare' (duration: 268.463928ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:02:19.906496Z","caller":"traceutil/trace.go:171","msg":"trace[2132015763] linearizableReadLoop","detail":"{readStateIndex:2035; appliedIndex:2031; }","duration":"467.048146ms","start":"2026-09-09T01:02:19.439426Z","end":"2026-09-09T01:02:19.906474Z","steps":["trace[2132015763] 'read index received' (duration: 34.188134ms)","trace[2132015763] 'applied index is now lower than readState.Index' (duration: 432.858102ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:02:19.90701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.575406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-09T01:02:19.907066Z","caller":"traceutil/trace.go:171","msg":"trace[194613478] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1953; }","duration":"467.634297ms","start":"2026-09-09T01:02:19.439417Z","end":"2026-09-09T01:02:19.907052Z","steps":["trace[194613478] 'agreement among raft nodes before linearized reading' (duration: 467.462244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:19.907265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:19.439281Z","time spent":"467.967516ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-09-09T01:02:19.906698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:19.403881Z","time spent":"502.709891ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:02:19.915365Z","caller":"traceutil/trace.go:171","msg":"trace[1299319512] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"144.021971ms","start":"2026-09-09T01:02:19.77128Z","end":"2026-09-09T01:02:19.915302Z","steps":["trace[1299319512] 'process raft request' (duration: 143.619844ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:19.915921Z","caller":"traceutil/trace.go:171","msg":"trace[1576323533] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"103.330119ms","start":"2026-09-09T01:02:19.812558Z","end":"2026-09-09T01:02:19.915888Z","steps":["trace[1576323533] 'process raft request' (duration: 102.548263ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:19.916035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.761028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:02:19.916113Z","caller":"traceutil/trace.go:171","msg":"trace[1519513910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1955; }","duration":"407.905141ms","start":"2026-09-09T01:02:19.50819Z","end":"2026-09-09T01:02:19.916096Z","steps":["trace[1519513910] 'agreement among raft nodes before linearized reading' (duration: 407.775618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:19.916155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:19.508139Z","time spent":"408.007783ms","remote":"127.0.0.1:42514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-09T01:02:19.916005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.675947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-09T01:02:19.916308Z","caller":"traceutil/trace.go:171","msg":"trace[1067063279] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1955; }","duration":"252.025954ms","start":"2026-09-09T01:02:19.664264Z","end":"2026-09-09T01:02:19.91629Z","steps":["trace[1067063279] 'agreement among raft nodes before linearized reading' (duration: 251.586155ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:19.917044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.785414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f9a830c-001a-4663-ae4d-f14d62d4803b\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-09-09T01:02:19.917105Z","caller":"traceutil/trace.go:171","msg":"trace[1210536742] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4f9a830c-001a-4663-ae4d-f14d62d4803b; range_end:; response_count:1; response_revision:1955; }","duration":"423.883365ms","start":"2026-09-09T01:02:19.493204Z","end":"2026-09-09T01:02:19.917087Z","steps":["trace[1210536742] 'agreement among raft nodes before linearized reading' (duration: 422.555689ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:19.917292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:19.493181Z","time spent":"423.951167ms","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3771,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f9a830c-001a-4663-ae4d-f14d62d4803b\" "} {"level":"info","ts":"2026-09-09T01:02:33.043816Z","caller":"traceutil/trace.go:171","msg":"trace[1990811032] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"136.748443ms","start":"2026-09-09T01:02:32.90703Z","end":"2026-09-09T01:02:33.043778Z","steps":["trace[1990811032] 'process raft request' (duration: 136.511879ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:53.792435Z","caller":"traceutil/trace.go:171","msg":"trace[1347694507] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"104.50649ms","start":"2026-09-09T01:02:53.687747Z","end":"2026-09-09T01:02:53.792253Z","steps":["trace[1347694507] 'process raft request' (duration: 104.317017ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:54.487049Z","caller":"traceutil/trace.go:171","msg":"trace[527997121] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"385.963038ms","start":"2026-09-09T01:02:54.101059Z","end":"2026-09-09T01:02:54.487022Z","steps":["trace[527997121] 'process raft request' (duration: 385.687064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:54.48727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:54.10104Z","time spent":"386.153862ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:02:54.708783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.085616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:02:54.709088Z","caller":"traceutil/trace.go:171","msg":"trace[2065021530] linearizableReadLoop","detail":"{readStateIndex:2281; appliedIndex:2278; }","duration":"558.643905ms","start":"2026-09-09T01:02:54.15043Z","end":"2026-09-09T01:02:54.709074Z","steps":["trace[2065021530] 'read index received' (duration: 336.250095ms)","trace[2065021530] 'applied index is now lower than readState.Index' (duration: 222.393ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:02:54.70926Z","caller":"traceutil/trace.go:171","msg":"trace[1136583086] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"607.136818ms","start":"2026-09-09T01:02:54.102092Z","end":"2026-09-09T01:02:54.709228Z","steps":["trace[1136583086] 'process raft request' (duration: 606.906724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:54.70944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.745655ms","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-09-09T01:02:54.709488Z","caller":"traceutil/trace.go:171","msg":"trace[1823214612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2190; }","duration":"367.829967ms","start":"2026-09-09T01:02:54.341648Z","end":"2026-09-09T01:02:54.709478Z","steps":["trace[1823214612] 'agreement among raft nodes before linearized reading' (duration: 367.683165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:54.70952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:54.341629Z","time spent":"367.884458ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-09T01:02:54.709634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.532938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:02:54.709435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:54.102081Z","time spent":"607.26175ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:02:54.709668Z","caller":"traceutil/trace.go:171","msg":"trace[296976093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2190; }","duration":"202.599169ms","start":"2026-09-09T01:02:54.50706Z","end":"2026-09-09T01:02:54.709659Z","steps":["trace[296976093] 'agreement among raft nodes before linearized reading' (duration: 202.547928ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:54.709266Z","caller":"traceutil/trace.go:171","msg":"trace[804358098] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"607.626786ms","start":"2026-09-09T01:02:54.101615Z","end":"2026-09-09T01:02:54.709241Z","steps":["trace[804358098] 'process raft request' (duration: 414.015761ms)","trace[804358098] 'compare' (duration: 192.569837ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:02:54.709464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.196439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-09T01:02:54.709988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.853063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-09T01:02:54.710025Z","caller":"traceutil/trace.go:171","msg":"trace[1658917216] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2190; }","duration":"397.936724ms","start":"2026-09-09T01:02:54.312077Z","end":"2026-09-09T01:02:54.710014Z","steps":["trace[1658917216] 'agreement among raft nodes before linearized reading' (duration: 397.835713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:54.710065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:54.312058Z","time spent":"398.000926ms","remote":"127.0.0.1:50192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-09T01:02:54.710178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.459045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-09T01:02:54.710355Z","caller":"traceutil/trace.go:171","msg":"trace[1105857386] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2190; }","duration":"419.562526ms","start":"2026-09-09T01:02:54.290666Z","end":"2026-09-09T01:02:54.710228Z","steps":["trace[1105857386] 'agreement among raft nodes before linearized reading' (duration: 419.259311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:54.710436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:54.290647Z","time spent":"419.771919ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-09T01:02:54.709296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.866589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-09T01:02:54.710525Z","caller":"traceutil/trace.go:171","msg":"trace[2049438975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2190; }","duration":"560.11994ms","start":"2026-09-09T01:02:54.150395Z","end":"2026-09-09T01:02:54.710515Z","steps":["trace[2049438975] 'agreement among raft nodes before linearized reading' (duration: 558.811668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:54.710555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:54.150379Z","time spent":"560.16794ms","remote":"127.0.0.1:42774","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-09-09T01:02:54.710634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:54.101585Z","time spent":"608.202155ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:02:54.71075Z","caller":"traceutil/trace.go:171","msg":"trace[2064968863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2190; }","duration":"554.765798ms","start":"2026-09-09T01:02:54.155234Z","end":"2026-09-09T01:02:54.71Z","steps":["trace[2064968863] 'agreement among raft nodes before linearized reading' (duration: 554.082127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:54.710788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:54.155225Z","time spent":"555.556741ms","remote":"127.0.0.1:42774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-09T01:02:54.852508Z","caller":"traceutil/trace.go:171","msg":"trace[92752194] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"138.945107ms","start":"2026-09-09T01:02:54.713534Z","end":"2026-09-09T01:02:54.852479Z","steps":["trace[92752194] 'process raft request' (duration: 115.149798ms)","trace[92752194] 'compare' (duration: 23.570015ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:02:54.852681Z","caller":"traceutil/trace.go:171","msg":"trace[1499334310] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"138.758743ms","start":"2026-09-09T01:02:54.713913Z","end":"2026-09-09T01:02:54.852672Z","steps":["trace[1499334310] 'process raft request' (duration: 138.490809ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:54.852749Z","caller":"traceutil/trace.go:171","msg":"trace[89363431] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"138.470348ms","start":"2026-09-09T01:02:54.714272Z","end":"2026-09-09T01:02:54.852743Z","steps":["trace[89363431] 'process raft request' (duration: 138.272094ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:54.852887Z","caller":"traceutil/trace.go:171","msg":"trace[1919794525] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"136.880831ms","start":"2026-09-09T01:02:54.715998Z","end":"2026-09-09T01:02:54.852879Z","steps":["trace[1919794525] 'process raft request' (duration: 136.673687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:03:01.042579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.990403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:03:01.042715Z","caller":"traceutil/trace.go:171","msg":"trace[2009531874] linearizableReadLoop","detail":"{readStateIndex:2316; appliedIndex:2315; }","duration":"134.650769ms","start":"2026-09-09T01:03:00.908042Z","end":"2026-09-09T01:03:01.042692Z","steps":["trace[2009531874] 'read index received' (duration: 30.393812ms)","trace[2009531874] 'applied index is now lower than readState.Index' (duration: 104.255687ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:03:01.042911Z","caller":"traceutil/trace.go:171","msg":"trace[930913185] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"153.766811ms","start":"2026-09-09T01:03:00.889127Z","end":"2026-09-09T01:03:01.042894Z","steps":["trace[930913185] 'process raft request' (duration: 49.339911ms)","trace[930913185] 'compare' (duration: 103.834829ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:03:01.043252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.201058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-09T01:03:01.043299Z","caller":"traceutil/trace.go:171","msg":"trace[94121407] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2224; }","duration":"135.269858ms","start":"2026-09-09T01:03:00.908011Z","end":"2026-09-09T01:03:01.043281Z","steps":["trace[94121407] 'agreement among raft nodes before linearized reading' (duration: 135.100505ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:03:01.043511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.263206ms","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-09-09T01:03:01.043622Z","caller":"traceutil/trace.go:171","msg":"trace[599356091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2224; }","duration":"123.433779ms","start":"2026-09-09T01:03:00.920162Z","end":"2026-09-09T01:03:01.043595Z","steps":["trace[599356091] 'agreement among raft nodes before linearized reading' (duration: 122.925711ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:03:01.158274Z","caller":"traceutil/trace.go:171","msg":"trace[1131626661] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"108.920221ms","start":"2026-09-09T01:03:01.049325Z","end":"2026-09-09T01:03:01.158245Z","steps":["trace[1131626661] 'process raft request' (duration: 97.233785ms)","trace[1131626661] 'compare' (duration: 11.563034ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:03:49.588097Z","caller":"traceutil/trace.go:171","msg":"trace[407691591] transaction","detail":"{read_only:false; response_revision:2534; number_of_response:1; }","duration":"104.749958ms","start":"2026-09-09T01:03:49.483303Z","end":"2026-09-09T01:03:49.588053Z","steps":["trace[407691591] 'process raft request' (duration: 104.294171ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:04:03.073844Z","caller":"traceutil/trace.go:171","msg":"trace[502482810] transaction","detail":"{read_only:false; response_revision:2659; number_of_response:1; }","duration":"214.919608ms","start":"2026-09-09T01:04:02.858893Z","end":"2026-09-09T01:04:03.073813Z","steps":["trace[502482810] 'process raft request' (duration: 214.721845ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:04:36.163846Z","caller":"traceutil/trace.go:171","msg":"trace[1455824847] linearizableReadLoop","detail":"{readStateIndex:2958; appliedIndex:2957; }","duration":"261.275872ms","start":"2026-09-09T01:04:35.902535Z","end":"2026-09-09T01:04:36.16381Z","steps":["trace[1455824847] 'read index received' (duration: 261.082574ms)","trace[1455824847] 'applied index is now lower than readState.Index' (duration: 191.758µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:04:36.164138Z","caller":"traceutil/trace.go:171","msg":"trace[86290722] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"278.483632ms","start":"2026-09-09T01:04:35.885628Z","end":"2026-09-09T01:04:36.164112Z","steps":["trace[86290722] 'process raft request' (duration: 278.005422ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:04:36.164283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.730621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T01:04:36.164347Z","caller":"traceutil/trace.go:171","msg":"trace[272980429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2845; }","duration":"261.850157ms","start":"2026-09-09T01:04:35.90248Z","end":"2026-09-09T01:04:36.16433Z","steps":["trace[272980429] 'agreement among raft nodes before linearized reading' (duration: 261.486691ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:04:36.621622Z","caller":"traceutil/trace.go:171","msg":"trace[2047577184] linearizableReadLoop","detail":"{readStateIndex:2960; appliedIndex:2959; }","duration":"114.774069ms","start":"2026-09-09T01:04:36.50682Z","end":"2026-09-09T01:04:36.621594Z","steps":["trace[2047577184] 'read index received' (duration: 114.530698ms)","trace[2047577184] 'applied index is now lower than readState.Index' (duration: 240.75µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:04:36.621868Z","caller":"traceutil/trace.go:171","msg":"trace[1813791272] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"137.702353ms","start":"2026-09-09T01:04:36.48415Z","end":"2026-09-09T01:04:36.621853Z","steps":["trace[1813791272] 'process raft request' (duration: 137.296125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:04:36.621762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.920125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:04:36.622474Z","caller":"traceutil/trace.go:171","msg":"trace[401241359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2847; }","duration":"115.633106ms","start":"2026-09-09T01:04:36.506775Z","end":"2026-09-09T01:04:36.622409Z","steps":["trace[401241359] 'agreement among raft nodes before linearized reading' (duration: 114.935295ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.107365Z","caller":"traceutil/trace.go:171","msg":"trace[1690390763] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"176.224121ms","start":"2026-09-09T01:08:58.931116Z","end":"2026-09-09T01:08:59.10734Z","steps":["trace[1690390763] 'process raft request' (duration: 163.627751ms)","trace[1690390763] 'compare' (duration: 12.181852ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:08:59.1075Z","caller":"traceutil/trace.go:171","msg":"trace[1700140832] linearizableReadLoop","detail":"{readStateIndex:5008; appliedIndex:5006; }","duration":"174.801422ms","start":"2026-09-09T01:08:58.932671Z","end":"2026-09-09T01:08:59.107472Z","steps":["trace[1700140832] 'read index received' (duration: 6.410373ms)","trace[1700140832] 'applied index is now lower than readState.Index' (duration: 168.390039ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:08:59.107662Z","caller":"traceutil/trace.go:171","msg":"trace[1830003970] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"175.452405ms","start":"2026-09-09T01:08:58.932196Z","end":"2026-09-09T01:08:59.107648Z","steps":["trace[1830003970] 'process raft request' (duration: 175.044237ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:08:59.107859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.237109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-09T01:08:59.107908Z","caller":"traceutil/trace.go:171","msg":"trace[1816903015] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4816; }","duration":"174.304651ms","start":"2026-09-09T01:08:58.933589Z","end":"2026-09-09T01:08:59.107894Z","steps":["trace[1816903015] 'agreement among raft nodes before linearized reading' (duration: 174.085197ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:08:59.107975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.259981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4737"} {"level":"info","ts":"2026-09-09T01:08:59.108049Z","caller":"traceutil/trace.go:171","msg":"trace[2031523327] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4816; }","duration":"175.70156ms","start":"2026-09-09T01:08:58.932335Z","end":"2026-09-09T01:08:59.108037Z","steps":["trace[2031523327] 'agreement among raft nodes before linearized reading' (duration: 175.325033ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.108038Z","caller":"traceutil/trace.go:171","msg":"trace[396778782] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"171.745518ms","start":"2026-09-09T01:08:58.936267Z","end":"2026-09-09T01:08:59.108012Z","steps":["trace[396778782] 'process raft request' (duration: 171.151696ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:08:59.108095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.41342ms","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-09-09T01:08:59.10813Z","caller":"traceutil/trace.go:171","msg":"trace[682412238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4816; }","duration":"104.475921ms","start":"2026-09-09T01:08:59.003645Z","end":"2026-09-09T01:08:59.108121Z","steps":["trace[682412238] 'agreement among raft nodes before linearized reading' (duration: 104.379239ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:08:59.10821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.547817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-8qsd7.18d3815e0001d673\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-09-09T01:08:59.108244Z","caller":"traceutil/trace.go:171","msg":"trace[319645779] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-8qsd7.18d3815e0001d673; range_end:; response_count:1; response_revision:4816; }","duration":"110.612267ms","start":"2026-09-09T01:08:58.997624Z","end":"2026-09-09T01:08:59.108236Z","steps":["trace[319645779] 'agreement among raft nodes before linearized reading' (duration: 110.519796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:08:59.108286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.941945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-09-09T01:08:59.108377Z","caller":"traceutil/trace.go:171","msg":"trace[1926752295] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4816; }","duration":"161.071068ms","start":"2026-09-09T01:08:58.947283Z","end":"2026-09-09T01:08:59.108354Z","steps":["trace[1926752295] 'agreement among raft nodes before linearized reading' (duration: 160.769572ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.317848Z","caller":"traceutil/trace.go:171","msg":"trace[1613222526] linearizableReadLoop","detail":"{readStateIndex:5016; appliedIndex:5015; }","duration":"191.812721ms","start":"2026-09-09T01:08:59.126006Z","end":"2026-09-09T01:08:59.317819Z","steps":["trace[1613222526] 'read index received' (duration: 191.479474ms)","trace[1613222526] 'applied index is now lower than readState.Index' (duration: 332.067µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:08:59.318002Z","caller":"traceutil/trace.go:171","msg":"trace[1301927877] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"197.668962ms","start":"2026-09-09T01:08:59.12028Z","end":"2026-09-09T01:08:59.317949Z","steps":["trace[1301927877] 'process raft request' (duration: 196.991708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:08:59.31827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.236729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-09T01:08:59.318346Z","caller":"traceutil/trace.go:171","msg":"trace[1774274896] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4823; }","duration":"192.350902ms","start":"2026-09-09T01:08:59.125974Z","end":"2026-09-09T01:08:59.318324Z","steps":["trace[1774274896] 'agreement among raft nodes before linearized reading' (duration: 192.094507ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.325039Z","caller":"traceutil/trace.go:171","msg":"trace[73200363] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"195.493455ms","start":"2026-09-09T01:08:59.129519Z","end":"2026-09-09T01:08:59.325012Z","steps":["trace[73200363] 'process raft request' (duration: 194.839372ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.325311Z","caller":"traceutil/trace.go:171","msg":"trace[1145919275] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"194.801332ms","start":"2026-09-09T01:08:59.130462Z","end":"2026-09-09T01:08:59.325263Z","steps":["trace[1145919275] 'process raft request' (duration: 194.204419ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.325922Z","caller":"traceutil/trace.go:171","msg":"trace[1705104245] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"195.567648ms","start":"2026-09-09T01:08:59.130313Z","end":"2026-09-09T01:08:59.325881Z","steps":["trace[1705104245] 'process raft request' (duration: 194.306751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:08:59.326028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.246169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-09T01:08:59.326071Z","caller":"traceutil/trace.go:171","msg":"trace[1676726407] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4827; }","duration":"193.30098ms","start":"2026-09-09T01:08:59.132757Z","end":"2026-09-09T01:08:59.326058Z","steps":["trace[1676726407] 'agreement among raft nodes before linearized reading' (duration: 192.494564ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:08:59.326054Z","caller":"traceutil/trace.go:171","msg":"trace[1896358634] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"196.362114ms","start":"2026-09-09T01:08:59.129671Z","end":"2026-09-09T01:08:59.326033Z","steps":["trace[1896358634] 'process raft request' (duration: 194.888993ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:12.44351Z","caller":"traceutil/trace.go:171","msg":"trace[136613807] linearizableReadLoop","detail":"{readStateIndex:5222; appliedIndex:5221; }","duration":"268.216457ms","start":"2026-09-09T01:09:12.175269Z","end":"2026-09-09T01:09:12.443485Z","steps":["trace[136613807] 'read index received' (duration: 268.076434ms)","trace[136613807] 'applied index is now lower than readState.Index' (duration: 138.983µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:12.443565Z","caller":"traceutil/trace.go:171","msg":"trace[1949016156] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"271.247298ms","start":"2026-09-09T01:09:12.172298Z","end":"2026-09-09T01:09:12.443545Z","steps":["trace[1949016156] 'process raft request' (duration: 271.001123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:12.443726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.417561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:09:12.443804Z","caller":"traceutil/trace.go:171","msg":"trace[1332585106] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:5022; }","duration":"268.553113ms","start":"2026-09-09T01:09:12.175232Z","end":"2026-09-09T01:09:12.443785Z","steps":["trace[1332585106] 'agreement among raft nodes before linearized reading' (duration: 268.351339ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:12.646723Z","caller":"traceutil/trace.go:171","msg":"trace[418586472] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"114.156744ms","start":"2026-09-09T01:09:12.532545Z","end":"2026-09-09T01:09:12.646701Z","steps":["trace[418586472] 'process raft request' (duration: 113.919109ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:12.646763Z","caller":"traceutil/trace.go:171","msg":"trace[1893442786] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"114.163565ms","start":"2026-09-09T01:09:12.532578Z","end":"2026-09-09T01:09:12.646741Z","steps":["trace[1893442786] 'process raft request' (duration: 114.017982ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:12.647067Z","caller":"traceutil/trace.go:171","msg":"trace[1875332345] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"113.297756ms","start":"2026-09-09T01:09:12.533757Z","end":"2026-09-09T01:09:12.647055Z","steps":["trace[1875332345] 'process raft request' (duration: 112.893198ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:12.868996Z","caller":"traceutil/trace.go:171","msg":"trace[1182308125] linearizableReadLoop","detail":"{readStateIndex:5237; appliedIndex:5236; }","duration":"129.506945ms","start":"2026-09-09T01:09:12.739467Z","end":"2026-09-09T01:09:12.868974Z","steps":["trace[1182308125] 'read index received' (duration: 129.29681ms)","trace[1182308125] 'applied index is now lower than readState.Index' (duration: 209.335µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:12.869204Z","caller":"traceutil/trace.go:171","msg":"trace[1819351496] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"147.394358ms","start":"2026-09-09T01:09:12.721781Z","end":"2026-09-09T01:09:12.869175Z","steps":["trace[1819351496] 'process raft request' (duration: 147.038261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:12.869495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.011265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-09T01:09:12.869571Z","caller":"traceutil/trace.go:171","msg":"trace[990697914] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5036; }","duration":"130.129827ms","start":"2026-09-09T01:09:12.739419Z","end":"2026-09-09T01:09:12.869549Z","steps":["trace[990697914] 'agreement among raft nodes before linearized reading' (duration: 129.638967ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:13.113478Z","caller":"traceutil/trace.go:171","msg":"trace[1663082518] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"132.956372ms","start":"2026-09-09T01:09:12.980493Z","end":"2026-09-09T01:09:13.113449Z","steps":["trace[1663082518] 'process raft request' (duration: 101.657918ms)","trace[1663082518] 'compare' (duration: 31.182601ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:13.192492Z","caller":"traceutil/trace.go:171","msg":"trace[1130774400] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"126.772118ms","start":"2026-09-09T01:09:13.065693Z","end":"2026-09-09T01:09:13.192466Z","steps":["trace[1130774400] 'process raft request' (duration: 126.474642ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:13.193122Z","caller":"traceutil/trace.go:171","msg":"trace[85570107] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"127.275257ms","start":"2026-09-09T01:09:13.065835Z","end":"2026-09-09T01:09:13.19311Z","steps":["trace[85570107] 'process raft request' (duration: 126.493701ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:13.194803Z","caller":"traceutil/trace.go:171","msg":"trace[1767575443] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"128.87062ms","start":"2026-09-09T01:09:13.0659Z","end":"2026-09-09T01:09:13.19477Z","steps":["trace[1767575443] 'process raft request' (duration: 127.176165ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:13.195518Z","caller":"traceutil/trace.go:171","msg":"trace[1449015962] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"128.940941ms","start":"2026-09-09T01:09:13.066556Z","end":"2026-09-09T01:09:13.195497Z","steps":["trace[1449015962] 'process raft request' (duration: 126.895739ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:26.329328Z","caller":"traceutil/trace.go:171","msg":"trace[1983465344] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"100.521727ms","start":"2026-09-09T01:09:26.228783Z","end":"2026-09-09T01:09:26.329305Z","steps":["trace[1983465344] 'process raft request' (duration: 100.081298ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:26.778114Z","caller":"traceutil/trace.go:171","msg":"trace[2144951600] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"102.316672ms","start":"2026-09-09T01:09:26.675781Z","end":"2026-09-09T01:09:26.778098Z","steps":["trace[2144951600] 'process raft request' (duration: 102.20953ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:26.799123Z","caller":"traceutil/trace.go:171","msg":"trace[677499837] linearizableReadLoop","detail":"{readStateIndex:5473; appliedIndex:5469; }","duration":"118.834953ms","start":"2026-09-09T01:09:26.680273Z","end":"2026-09-09T01:09:26.799108Z","steps":["trace[677499837] 'read index received' (duration: 97.792682ms)","trace[677499837] 'applied index is now lower than readState.Index' (duration: 21.041561ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:26.799177Z","caller":"traceutil/trace.go:171","msg":"trace[2029941563] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"123.102807ms","start":"2026-09-09T01:09:26.676042Z","end":"2026-09-09T01:09:26.799145Z","steps":["trace[2029941563] 'process raft request' (duration: 123.008746ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:26.799226Z","caller":"traceutil/trace.go:171","msg":"trace[225784431] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"122.18368ms","start":"2026-09-09T01:09:26.677031Z","end":"2026-09-09T01:09:26.799215Z","steps":["trace[225784431] 'process raft request' (duration: 122.053477ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:26.799151Z","caller":"traceutil/trace.go:171","msg":"trace[1348045587] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"123.170019ms","start":"2026-09-09T01:09:26.675948Z","end":"2026-09-09T01:09:26.799118Z","steps":["trace[1348045587] 'process raft request' (duration: 123.011366ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:26.799644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.003226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:09:26.799718Z","caller":"traceutil/trace.go:171","msg":"trace[461947688] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5267; }","duration":"119.458955ms","start":"2026-09-09T01:09:26.680242Z","end":"2026-09-09T01:09:26.799701Z","steps":["trace[461947688] 'agreement among raft nodes before linearized reading' (duration: 118.978916ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:29.236762Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2807} {"level":"info","ts":"2026-09-09T01:09:29.34575Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2807,"took":"108.138762ms","hash":1514178634,"current-db-size-bytes":51298304,"current-db-size":"51 MB","current-db-size-in-use-bytes":27590656,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T01:09:29.345795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1514178634,"revision":2807,"compact-revision":-1} {"level":"info","ts":"2026-09-09T01:10:12.914049Z","caller":"traceutil/trace.go:171","msg":"trace[1184866120] transaction","detail":"{read_only:false; response_revision:5659; number_of_response:1; }","duration":"104.367748ms","start":"2026-09-09T01:10:12.809662Z","end":"2026-09-09T01:10:12.91403Z","steps":["trace[1184866120] 'process raft request' (duration: 104.214105ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:12.914309Z","caller":"traceutil/trace.go:171","msg":"trace[943821899] transaction","detail":"{read_only:false; response_revision:5660; number_of_response:1; }","duration":"103.832909ms","start":"2026-09-09T01:10:12.810412Z","end":"2026-09-09T01:10:12.914245Z","steps":["trace[943821899] 'process raft request' (duration: 103.581723ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:17.121229Z","caller":"traceutil/trace.go:171","msg":"trace[410682705] transaction","detail":"{read_only:false; response_revision:5685; number_of_response:1; }","duration":"182.587664ms","start":"2026-09-09T01:10:16.938609Z","end":"2026-09-09T01:10:17.121196Z","steps":["trace[410682705] 'process raft request' (duration: 143.243994ms)","trace[410682705] 'compare' (duration: 38.843661ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:18.096823Z","caller":"traceutil/trace.go:171","msg":"trace[970574906] linearizableReadLoop","detail":"{readStateIndex:5909; appliedIndex:5908; }","duration":"185.198582ms","start":"2026-09-09T01:10:17.911598Z","end":"2026-09-09T01:10:18.096797Z","steps":["trace[970574906] 'read index received' (duration: 184.907116ms)","trace[970574906] 'applied index is now lower than readState.Index' (duration: 290.316µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:18.096969Z","caller":"traceutil/trace.go:171","msg":"trace[950590039] transaction","detail":"{read_only:false; response_revision:5689; number_of_response:1; }","duration":"191.823748ms","start":"2026-09-09T01:10:17.905127Z","end":"2026-09-09T01:10:18.096951Z","steps":["trace[950590039] 'process raft request' (duration: 191.44554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:18.097146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.536808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:10:18.097194Z","caller":"traceutil/trace.go:171","msg":"trace[1882618449] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5689; }","duration":"185.6384ms","start":"2026-09-09T01:10:17.911544Z","end":"2026-09-09T01:10:18.097183Z","steps":["trace[1882618449] 'agreement among raft nodes before linearized reading' (duration: 185.548929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:18.097415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.982668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T01:10:18.097501Z","caller":"traceutil/trace.go:171","msg":"trace[2036587224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5689; }","duration":"107.089721ms","start":"2026-09-09T01:10:17.990397Z","end":"2026-09-09T01:10:18.097487Z","steps":["trace[2036587224] 'agreement among raft nodes before linearized reading' (duration: 106.888377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:13:07.592534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.360573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:13:07.59303Z","caller":"traceutil/trace.go:171","msg":"trace[172494662] linearizableReadLoop","detail":"{readStateIndex:7116; appliedIndex:7115; }","duration":"237.62085ms","start":"2026-09-09T01:13:07.355382Z","end":"2026-09-09T01:13:07.593003Z","steps":["trace[172494662] 'read index received' (duration: 39.707023ms)","trace[172494662] 'applied index is now lower than readState.Index' (duration: 197.910776ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:13:07.593152Z","caller":"traceutil/trace.go:171","msg":"trace[898868966] transaction","detail":"{read_only:false; response_revision:6857; number_of_response:1; }","duration":"290.939549ms","start":"2026-09-09T01:13:07.302176Z","end":"2026-09-09T01:13:07.593115Z","steps":["trace[898868966] 'process raft request' (duration: 92.885896ms)","trace[898868966] 'compare' (duration: 197.262279ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:13:07.593234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.794637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:13:07.59337Z","caller":"traceutil/trace.go:171","msg":"trace[1668053302] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:6857; }","duration":"238.010338ms","start":"2026-09-09T01:13:07.355341Z","end":"2026-09-09T01:13:07.593351Z","steps":["trace[1668053302] 'agreement among raft nodes before linearized reading' (duration: 237.786797ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:13:07.782566Z","caller":"traceutil/trace.go:171","msg":"trace[923771771] transaction","detail":"{read_only:false; response_revision:6858; number_of_response:1; }","duration":"122.54544ms","start":"2026-09-09T01:13:07.659998Z","end":"2026-09-09T01:13:07.782543Z","steps":["trace[923771771] 'process raft request' (duration: 122.192144ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:14:29.243349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5286} {"level":"info","ts":"2026-09-09T01:14:29.323945Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5286,"took":"79.403022ms","hash":2542719959,"current-db-size-bytes":51298304,"current-db-size":"51 MB","current-db-size-in-use-bytes":19353600,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-09T01:14:29.324042Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2542719959,"revision":5286,"compact-revision":2807}