{"level":"warn","ts":"2026-07-30T20:59:23.837662Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T20:59:23.837736Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-30T20:59:23.83855Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T20:59:23.838568Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-07-30T20:59:23.838588Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T20:59:23.839169Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-07-30T20:59:23.839239Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-30T20:59:23.841979Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.517213ms"} {"level":"info","ts":"2026-07-30T20:59:23.846077Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-07-30T20:59:23.846149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T20:59:23.846175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-07-30T20:59:23.846184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T20:59:23.8462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-07-30T20:59:23.846238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-07-30T20:59:23.851473Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T20:59:23.852917Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T20:59:23.854036Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-30T20:59:23.856489Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T20:59:23.857565Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T20:59:23.857654Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T20:59:23.857708Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T20:59:23.857717Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T20:59:23.859614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-07-30T20:59:23.859727Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-07-30T20:59:23.861996Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T20:59:23.862112Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-07-30T20:59:23.862141Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-07-30T20:59:23.862263Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T20:59:23.862308Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T20:59:24.24741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T20:59:24.247466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T20:59:24.2475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-07-30T20:59:24.247515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-07-30T20:59:24.247524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-07-30T20:59:24.247535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-07-30T20:59:24.247548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-07-30T20:59:24.249975Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T20:59:24.250046Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T20:59:24.250218Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T20:59:24.250613Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:59:24.250737Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T20:59:24.250767Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T20:59:24.254158Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T20:59:24.254228Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-07-30T20:59:24.254505Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:59:24.25462Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:59:24.254658Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:59:28.188639Z","caller":"traceutil/trace.go:171","msg":"trace[1306202996] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"123.172806ms","start":"2026-07-30T20:59:28.065422Z","end":"2026-07-30T20:59:28.188595Z","steps":["trace[1306202996] 'process raft request' (duration: 32.032863ms)","trace[1306202996] 'compare' (duration: 90.876486ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:59:28.341379Z","caller":"traceutil/trace.go:171","msg":"trace[1692928247] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"141.945947ms","start":"2026-07-30T20:59:28.199401Z","end":"2026-07-30T20:59:28.341347Z","steps":["trace[1692928247] 'process raft request' (duration: 125.812252ms)","trace[1692928247] 'compare' (duration: 15.953861ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:59:28.483875Z","caller":"traceutil/trace.go:171","msg":"trace[1296902619] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"104.256522ms","start":"2026-07-30T20:59:28.379575Z","end":"2026-07-30T20:59:28.483832Z","steps":["trace[1296902619] 'process raft request' (duration: 97.050201ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:38.811269Z","caller":"traceutil/trace.go:171","msg":"trace[97700030] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"204.050042ms","start":"2026-07-30T20:59:38.607197Z","end":"2026-07-30T20:59:38.811247Z","steps":["trace[97700030] 'read index received' (duration: 203.849287ms)","trace[97700030] 'applied index is now lower than readState.Index' (duration: 200.095µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:59:38.811301Z","caller":"traceutil/trace.go:171","msg":"trace[1658323040] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"211.369596ms","start":"2026-07-30T20:59:38.599908Z","end":"2026-07-30T20:59:38.811278Z","steps":["trace[1658323040] 'process raft request' (duration: 211.227502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:38.811368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.158814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:59:38.811414Z","caller":"traceutil/trace.go:171","msg":"trace[582917781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"204.235887ms","start":"2026-07-30T20:59:38.607168Z","end":"2026-07-30T20:59:38.811404Z","steps":["trace[582917781] 'agreement among raft nodes before linearized reading' (duration: 204.163405ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:59:44.809356Z","caller":"traceutil/trace.go:171","msg":"trace[631540728] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"202.942145ms","start":"2026-07-30T20:59:44.606387Z","end":"2026-07-30T20:59:44.809329Z","steps":["trace[631540728] 'read index received' (duration: 202.748039ms)","trace[631540728] 'applied index is now lower than readState.Index' (duration: 193.266µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:59:44.80943Z","caller":"traceutil/trace.go:171","msg":"trace[435019852] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"711.323743ms","start":"2026-07-30T20:59:44.098077Z","end":"2026-07-30T20:59:44.809401Z","steps":["trace[435019852] 'process raft request' (duration: 711.122157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:44.809459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.064159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:59:44.809504Z","caller":"traceutil/trace.go:171","msg":"trace[1614537406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"203.160321ms","start":"2026-07-30T20:59:44.606334Z","end":"2026-07-30T20:59:44.809494Z","steps":["trace[1614537406] 'agreement among raft nodes before linearized reading' (duration: 203.084759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:44.810027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:44.098061Z","time spent":"711.437396ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:59:50.065489Z","caller":"traceutil/trace.go:171","msg":"trace[230534957] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"378.900564ms","start":"2026-07-30T20:59:49.68655Z","end":"2026-07-30T20:59:50.06545Z","steps":["trace[230534957] 'process raft request' (duration: 378.72894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:59:50.065716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:49.686534Z","time spent":"379.062368ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:59:50.517751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.810267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:59:50.517874Z","caller":"traceutil/trace.go:171","msg":"trace[1076444651] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"689.6425ms","start":"2026-07-30T20:59:49.828211Z","end":"2026-07-30T20:59:50.517854Z","steps":["trace[1076444651] 'process raft request' (duration: 307.473425ms)","trace[1076444651] 'compare' (duration: 381.658412ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:59:50.517952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:59:49.828171Z","time spent":"689.740792ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:00:02.934537Z","caller":"traceutil/trace.go:171","msg":"trace[138118638] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"157.510288ms","start":"2026-07-30T21:00:02.776991Z","end":"2026-07-30T21:00:02.934502Z","steps":["trace[138118638] 'process raft request' (duration: 154.581975ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:02.934717Z","caller":"traceutil/trace.go:171","msg":"trace[1501731753] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"157.180119ms","start":"2026-07-30T21:00:02.777509Z","end":"2026-07-30T21:00:02.934689Z","steps":["trace[1501731753] 'process raft request' (duration: 156.728767ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:02.93486Z","caller":"traceutil/trace.go:171","msg":"trace[1601871188] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"147.052945ms","start":"2026-07-30T21:00:02.787793Z","end":"2026-07-30T21:00:02.934846Z","steps":["trace[1601871188] 'process raft request' (duration: 146.675056ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:02.934937Z","caller":"traceutil/trace.go:171","msg":"trace[1011426318] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"147.071725ms","start":"2026-07-30T21:00:02.787858Z","end":"2026-07-30T21:00:02.93493Z","steps":["trace[1011426318] 'process raft request' (duration: 146.951723ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:02.935015Z","caller":"traceutil/trace.go:171","msg":"trace[2040907101] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"146.815869ms","start":"2026-07-30T21:00:02.788185Z","end":"2026-07-30T21:00:02.935001Z","steps":["trace[2040907101] 'process raft request' (duration: 146.681636ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:02.935096Z","caller":"traceutil/trace.go:171","msg":"trace[1731848856] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"146.750538ms","start":"2026-07-30T21:00:02.788319Z","end":"2026-07-30T21:00:02.935069Z","steps":["trace[1731848856] 'process raft request' (duration: 146.578844ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:02.935144Z","caller":"traceutil/trace.go:171","msg":"trace[178781817] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"146.166653ms","start":"2026-07-30T21:00:02.78897Z","end":"2026-07-30T21:00:02.935137Z","steps":["trace[178781817] 'process raft request' (duration: 146.06522ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:02.935302Z","caller":"traceutil/trace.go:171","msg":"trace[1558019840] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"112.666403ms","start":"2026-07-30T21:00:02.822626Z","end":"2026-07-30T21:00:02.935292Z","steps":["trace[1558019840] 'process raft request' (duration: 112.591862ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:02.935321Z","caller":"traceutil/trace.go:171","msg":"trace[606083606] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"132.117111ms","start":"2026-07-30T21:00:02.803183Z","end":"2026-07-30T21:00:02.9353Z","steps":["trace[606083606] 'process raft request' (duration: 131.962887ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:02.935394Z","caller":"traceutil/trace.go:171","msg":"trace[519930496] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"140.034409ms","start":"2026-07-30T21:00:02.795235Z","end":"2026-07-30T21:00:02.93527Z","steps":["trace[519930496] 'process raft request' (duration: 139.830144ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:03.194718Z","caller":"traceutil/trace.go:171","msg":"trace[148647654] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"206.727229ms","start":"2026-07-30T21:00:02.987952Z","end":"2026-07-30T21:00:03.194679Z","steps":["trace[148647654] 'process raft request' (duration: 170.378509ms)","trace[148647654] 'compare' (duration: 36.198416ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:00:03.194994Z","caller":"traceutil/trace.go:171","msg":"trace[5366728] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"205.948191ms","start":"2026-07-30T21:00:02.989004Z","end":"2026-07-30T21:00:03.194953Z","steps":["trace[5366728] 'process raft request' (duration: 205.711904ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:03.195055Z","caller":"traceutil/trace.go:171","msg":"trace[889401294] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"119.682929ms","start":"2026-07-30T21:00:03.075346Z","end":"2026-07-30T21:00:03.195029Z","steps":["trace[889401294] 'process raft request' (duration: 119.554515ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:03.195218Z","caller":"traceutil/trace.go:171","msg":"trace[1614562931] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"153.584438ms","start":"2026-07-30T21:00:03.041625Z","end":"2026-07-30T21:00:03.195209Z","steps":["trace[1614562931] 'process raft request' (duration: 153.219489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:09.473414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767087894416199,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:00:09.807129Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.013758721s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T21:00:09.808666Z","caller":"traceutil/trace.go:171","msg":"trace[1646672152] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"835.940915ms","start":"2026-07-30T21:00:08.972687Z","end":"2026-07-30T21:00:09.808628Z","steps":["trace[1646672152] 'read index received' (duration: 834.702944ms)","trace[1646672152] 'applied index is now lower than readState.Index' (duration: 1.236751ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:00:09.808824Z","caller":"traceutil/trace.go:171","msg":"trace[1168064542] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.017230818s","start":"2026-07-30T21:00:08.791547Z","end":"2026-07-30T21:00:09.808778Z","steps":["trace[1168064542] 'process raft request' (duration: 1.016306295s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:09.808996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.72856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:00:09.809024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.959231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:00:09.809048Z","caller":"traceutil/trace.go:171","msg":"trace[1054535334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"827.822843ms","start":"2026-07-30T21:00:08.98121Z","end":"2026-07-30T21:00:09.809033Z","steps":["trace[1054535334] 'agreement among raft nodes before linearized reading' (duration: 827.618408ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:09.809081Z","caller":"traceutil/trace.go:171","msg":"trace[291038093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"202.037823ms","start":"2026-07-30T21:00:09.607026Z","end":"2026-07-30T21:00:09.809064Z","steps":["trace[291038093] 'agreement among raft nodes before linearized reading' (duration: 201.9391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:09.809091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:08.981204Z","time spent":"827.877954ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:00:09.809072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:08.791494Z","time spent":"1.017457234s","remote":"127.0.0.1:54568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:00:09.809048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.320785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-30T21:00:09.809123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.393235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sv8jm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-30T21:00:09.809203Z","caller":"traceutil/trace.go:171","msg":"trace[325090623] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sv8jm; range_end:; response_count:1; response_revision:632; }","duration":"163.487937ms","start":"2026-07-30T21:00:09.645686Z","end":"2026-07-30T21:00:09.809174Z","steps":["trace[325090623] 'agreement among raft nodes before linearized reading' (duration: 163.186349ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:09.809275Z","caller":"traceutil/trace.go:171","msg":"trace[1101359034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"836.569631ms","start":"2026-07-30T21:00:08.972599Z","end":"2026-07-30T21:00:09.809169Z","steps":["trace[1101359034] 'agreement among raft nodes before linearized reading' (duration: 836.229413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:09.809337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:08.972534Z","time spent":"836.786717ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:00:09.809408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.092664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T21:00:09.809542Z","caller":"traceutil/trace.go:171","msg":"trace[684428512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"291.31202ms","start":"2026-07-30T21:00:09.518196Z","end":"2026-07-30T21:00:09.809508Z","steps":["trace[684428512] 'agreement among raft nodes before linearized reading' (duration: 290.860269ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:09.965777Z","caller":"traceutil/trace.go:171","msg":"trace[2069492168] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"150.89659ms","start":"2026-07-30T21:00:09.81485Z","end":"2026-07-30T21:00:09.965747Z","steps":["trace[2069492168] 'process raft request' (duration: 150.634503ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:09.966108Z","caller":"traceutil/trace.go:171","msg":"trace[58381085] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"151.103456ms","start":"2026-07-30T21:00:09.81496Z","end":"2026-07-30T21:00:09.966063Z","steps":["trace[58381085] 'process raft request' (duration: 150.744637ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:09.966352Z","caller":"traceutil/trace.go:171","msg":"trace[1281400508] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"129.446473ms","start":"2026-07-30T21:00:09.836863Z","end":"2026-07-30T21:00:09.966309Z","steps":["trace[1281400508] 'process raft request' (duration: 129.148186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:10.658417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.924068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T21:00:10.658527Z","caller":"traceutil/trace.go:171","msg":"trace[316336899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"364.119383ms","start":"2026-07-30T21:00:10.294389Z","end":"2026-07-30T21:00:10.658508Z","steps":["trace[316336899] 'range keys from in-memory index tree' (duration: 363.652922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:10.658689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:10.29435Z","time spent":"364.329568ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T21:00:10.659445Z","caller":"traceutil/trace.go:171","msg":"trace[1457078800] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"282.541309ms","start":"2026-07-30T21:00:10.376863Z","end":"2026-07-30T21:00:10.659404Z","steps":["trace[1457078800] 'process raft request' (duration: 270.475327ms)","trace[1457078800] 'compare' (duration: 11.102009ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:00:11.897143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.903227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:00:11.897432Z","caller":"traceutil/trace.go:171","msg":"trace[411084090] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.179100034s","start":"2026-07-30T21:00:10.718311Z","end":"2026-07-30T21:00:11.897411Z","steps":["trace[411084090] 'process raft request' (duration: 1.178964901s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:11.897472Z","caller":"traceutil/trace.go:171","msg":"trace[188714582] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.181506154s","start":"2026-07-30T21:00:10.715925Z","end":"2026-07-30T21:00:11.897431Z","steps":["trace[188714582] 'process raft request' (duration: 295.235268ms)","trace[188714582] 'compare' (duration: 885.6202ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:00:11.897532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:10.718275Z","time spent":"1.179204507s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:00:11.897654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:10.715886Z","time spent":"1.181691549s","remote":"127.0.0.1:54942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:00:16.931709Z","caller":"traceutil/trace.go:171","msg":"trace[1505747960] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"101.451312ms","start":"2026-07-30T21:00:16.830219Z","end":"2026-07-30T21:00:16.93167Z","steps":["trace[1505747960] 'process raft request' (duration: 101.126314ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:27.495113Z","caller":"traceutil/trace.go:171","msg":"trace[2052430459] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"223.888229ms","start":"2026-07-30T21:00:27.271201Z","end":"2026-07-30T21:00:27.495089Z","steps":["trace[2052430459] 'read index received' (duration: 223.695644ms)","trace[2052430459] 'applied index is now lower than readState.Index' (duration: 191.405µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:00:27.495232Z","caller":"traceutil/trace.go:171","msg":"trace[842804847] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"470.125674ms","start":"2026-07-30T21:00:27.025068Z","end":"2026-07-30T21:00:27.495194Z","steps":["trace[842804847] 'process raft request' (duration: 469.885508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:27.495371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.152655ms","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-07-30T21:00:27.495488Z","caller":"traceutil/trace.go:171","msg":"trace[1521076037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"224.32327ms","start":"2026-07-30T21:00:27.271152Z","end":"2026-07-30T21:00:27.495476Z","steps":["trace[1521076037] 'agreement among raft nodes before linearized reading' (duration: 224.090614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:27.495489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.604149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-30T21:00:27.495555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:00:27.025038Z","time spent":"470.273708ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:00:27.495571Z","caller":"traceutil/trace.go:171","msg":"trace[224032847] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:803; }","duration":"200.740282ms","start":"2026-07-30T21:00:27.294811Z","end":"2026-07-30T21:00:27.495551Z","steps":["trace[224032847] 'agreement among raft nodes before linearized reading' (duration: 200.498566ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:07.663447Z","caller":"traceutil/trace.go:171","msg":"trace[1702611567] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"367.296642ms","start":"2026-07-30T21:01:07.296109Z","end":"2026-07-30T21:01:07.663406Z","steps":["trace[1702611567] 'read index received' (duration: 366.947893ms)","trace[1702611567] 'applied index is now lower than readState.Index' (duration: 347.189µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:07.66356Z","caller":"traceutil/trace.go:171","msg":"trace[1434910801] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"639.079464ms","start":"2026-07-30T21:01:07.02444Z","end":"2026-07-30T21:01:07.663519Z","steps":["trace[1434910801] 'process raft request' (duration: 638.751146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:07.663739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:07.024407Z","time spent":"639.241267ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:07.663756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.713593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:01:07.66375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.62228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T21:01:07.663847Z","caller":"traceutil/trace.go:171","msg":"trace[1101259070] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:1090; }","duration":"363.833396ms","start":"2026-07-30T21:01:07.299988Z","end":"2026-07-30T21:01:07.663821Z","steps":["trace[1101259070] 'agreement among raft nodes before linearized reading' (duration: 363.675342ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:07.663856Z","caller":"traceutil/trace.go:171","msg":"trace[223370349] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:1090; }","duration":"367.798345ms","start":"2026-07-30T21:01:07.296037Z","end":"2026-07-30T21:01:07.663836Z","steps":["trace[223370349] 'agreement among raft nodes before linearized reading' (duration: 367.505687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:07.663903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:07.299981Z","time spent":"363.912178ms","remote":"127.0.0.1:54568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-07-30T21:01:07.663917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:07.296005Z","time spent":"367.894057ms","remote":"127.0.0.1:54524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-07-30T21:01:08.519671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.590182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:01:08.519811Z","caller":"traceutil/trace.go:171","msg":"trace[1106601768] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"444.781541ms","start":"2026-07-30T21:01:08.075016Z","end":"2026-07-30T21:01:08.519797Z","steps":["trace[1106601768] 'process raft request' (duration: 444.74066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:08.519861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:08.07498Z","time spent":"444.855203ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:08.520049Z","caller":"traceutil/trace.go:171","msg":"trace[26102420] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"756.650133ms","start":"2026-07-30T21:01:07.763343Z","end":"2026-07-30T21:01:08.519993Z","steps":["trace[26102420] 'read index received' (duration: 5.735303ms)","trace[26102420] 'applied index is now lower than readState.Index' (duration: 750.91108ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:08.52022Z","caller":"traceutil/trace.go:171","msg":"trace[406913403] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"807.991973ms","start":"2026-07-30T21:01:07.71221Z","end":"2026-07-30T21:01:08.520202Z","steps":["trace[406913403] 'process raft request' (duration: 56.773575ms)","trace[406913403] 'compare' (duration: 750.488859ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:08.520402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:07.712186Z","time spent":"808.074664ms","remote":"127.0.0.1:54524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:08.520452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.975378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-30T21:01:08.520486Z","caller":"traceutil/trace.go:171","msg":"trace[1500438735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1092; }","duration":"264.03161ms","start":"2026-07-30T21:01:08.256445Z","end":"2026-07-30T21:01:08.520477Z","steps":["trace[1500438735] 'agreement among raft nodes before linearized reading' (duration: 263.907387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:08.520482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.560817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:01:08.520606Z","caller":"traceutil/trace.go:171","msg":"trace[1955254235] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1092; }","duration":"216.72526ms","start":"2026-07-30T21:01:08.303851Z","end":"2026-07-30T21:01:08.520576Z","steps":["trace[1955254235] 'agreement among raft nodes before linearized reading' (duration: 216.489704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:08.520512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.953172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T21:01:08.520711Z","caller":"traceutil/trace.go:171","msg":"trace[1848360204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1092; }","duration":"265.194428ms","start":"2026-07-30T21:01:08.255496Z","end":"2026-07-30T21:01:08.52069Z","steps":["trace[1848360204] 'agreement among raft nodes before linearized reading' (duration: 264.89059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:08.52051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.156996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T21:01:08.520815Z","caller":"traceutil/trace.go:171","msg":"trace[812646706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1092; }","duration":"757.481604ms","start":"2026-07-30T21:01:07.763301Z","end":"2026-07-30T21:01:08.520782Z","steps":["trace[812646706] 'agreement among raft nodes before linearized reading' (duration: 757.083144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:08.520522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.664697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-30T21:01:08.520887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:07.763278Z","time spent":"757.588226ms","remote":"127.0.0.1:54708","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":"info","ts":"2026-07-30T21:01:08.52091Z","caller":"traceutil/trace.go:171","msg":"trace[862269475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1092; }","duration":"296.079587ms","start":"2026-07-30T21:01:08.224797Z","end":"2026-07-30T21:01:08.520877Z","steps":["trace[862269475] 'agreement among raft nodes before linearized reading' (duration: 295.526364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:33.421393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.989462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:01:33.422119Z","caller":"traceutil/trace.go:171","msg":"trace[198375528] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1384; }","duration":"130.788962ms","start":"2026-07-30T21:01:33.291292Z","end":"2026-07-30T21:01:33.422081Z","steps":["trace[198375528] 'count revisions from in-memory index tree' (duration: 129.847508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:33.421504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.218679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:01:33.422392Z","caller":"traceutil/trace.go:171","msg":"trace[1765164705] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1384; }","duration":"108.207215ms","start":"2026-07-30T21:01:33.314153Z","end":"2026-07-30T21:01:33.42236Z","steps":["trace[1765164705] 'count revisions from in-memory index tree' (duration: 107.018845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:35.214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767087894417742,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:01:35.714261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767087894417742,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:01:35.759677Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.082566855s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T21:01:35.76017Z","caller":"traceutil/trace.go:171","msg":"trace[1153840014] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"1.046447932s","start":"2026-07-30T21:01:34.713697Z","end":"2026-07-30T21:01:35.760145Z","steps":["trace[1153840014] 'read index received' (duration: 1.046134035s)","trace[1153840014] 'applied index is now lower than readState.Index' (duration: 313.007µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:35.760698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046995786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:01:35.760762Z","caller":"traceutil/trace.go:171","msg":"trace[853462275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1386; }","duration":"1.047112769s","start":"2026-07-30T21:01:34.713634Z","end":"2026-07-30T21:01:35.760746Z","steps":["trace[853462275] 'agreement among raft nodes before linearized reading' (duration: 1.046716248s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:35.760806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:34.713615Z","time spent":"1.047180891s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:01:35.760933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.34524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:01:35.761049Z","caller":"traceutil/trace.go:171","msg":"trace[1733910703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1386; }","duration":"944.518335ms","start":"2026-07-30T21:01:34.816502Z","end":"2026-07-30T21:01:35.76102Z","steps":["trace[1733910703] 'agreement among raft nodes before linearized reading' (duration: 944.162805ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:35.760588Z","caller":"traceutil/trace.go:171","msg":"trace[784072827] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"1.083547739s","start":"2026-07-30T21:01:34.676983Z","end":"2026-07-30T21:01:35.760531Z","steps":["trace[784072827] 'process raft request' (duration: 1.082939984s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:35.761102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:34.81648Z","time spent":"944.613416ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:01:35.761152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.4112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T21:01:35.761308Z","caller":"traceutil/trace.go:171","msg":"trace[3654480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1386; }","duration":"944.681118ms","start":"2026-07-30T21:01:34.816597Z","end":"2026-07-30T21:01:35.761278Z","steps":["trace[3654480] 'agreement among raft nodes before linearized reading' (duration: 944.057582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:35.761324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:34.676961Z","time spent":"1.084149375s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:35.761349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.673385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T21:01:35.761424Z","caller":"traceutil/trace.go:171","msg":"trace[1165669656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1386; }","duration":"986.791628ms","start":"2026-07-30T21:01:34.774617Z","end":"2026-07-30T21:01:35.761409Z","steps":["trace[1165669656] 'agreement among raft nodes before linearized reading' (duration: 985.994878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:35.761471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:34.774581Z","time spent":"986.877291ms","remote":"127.0.0.1:54708","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-07-30T21:01:35.76144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:34.816574Z","time spent":"944.816772ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T21:01:35.984504Z","caller":"traceutil/trace.go:171","msg":"trace[509178722] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"218.333677ms","start":"2026-07-30T21:01:35.766141Z","end":"2026-07-30T21:01:35.984474Z","steps":["trace[509178722] 'process raft request' (duration: 218.159642ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:36.007872Z","caller":"traceutil/trace.go:171","msg":"trace[22484312] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"241.537339ms","start":"2026-07-30T21:01:35.766315Z","end":"2026-07-30T21:01:36.007852Z","steps":["trace[22484312] 'process raft request' (duration: 241.411646ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:36.008187Z","caller":"traceutil/trace.go:171","msg":"trace[2027782369] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1436; }","duration":"113.964808ms","start":"2026-07-30T21:01:35.894199Z","end":"2026-07-30T21:01:36.008164Z","steps":["trace[2027782369] 'read index received' (duration: 90.252851ms)","trace[2027782369] 'applied index is now lower than readState.Index' (duration: 23.710797ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:36.008267Z","caller":"traceutil/trace.go:171","msg":"trace[303536695] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"241.092649ms","start":"2026-07-30T21:01:35.767159Z","end":"2026-07-30T21:01:36.008252Z","steps":["trace[303536695] 'process raft request' (duration: 240.962506ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:36.008193Z","caller":"traceutil/trace.go:171","msg":"trace[1886230409] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"241.443288ms","start":"2026-07-30T21:01:35.766717Z","end":"2026-07-30T21:01:36.00816Z","steps":["trace[1886230409] 'process raft request' (duration: 241.096179ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:36.00838Z","caller":"traceutil/trace.go:171","msg":"trace[427633223] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"241.248973ms","start":"2026-07-30T21:01:35.767041Z","end":"2026-07-30T21:01:36.00829Z","steps":["trace[427633223] 'process raft request' (duration: 240.997387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:36.008498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.282795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T21:01:36.008551Z","caller":"traceutil/trace.go:171","msg":"trace[511781196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1391; }","duration":"114.394968ms","start":"2026-07-30T21:01:35.894144Z","end":"2026-07-30T21:01:36.008539Z","steps":["trace[511781196] 'agreement among raft nodes before linearized reading' (duration: 114.133751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:38.232931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.787793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:01:38.233103Z","caller":"traceutil/trace.go:171","msg":"trace[1362697054] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"813.262619ms","start":"2026-07-30T21:01:37.419814Z","end":"2026-07-30T21:01:38.233077Z","steps":["trace[1362697054] 'process raft request' (duration: 459.183719ms)","trace[1362697054] 'compare' (duration: 353.171388ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:38.233195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:37.419779Z","time spent":"813.381593ms","remote":"127.0.0.1:54524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:38.248581Z","caller":"traceutil/trace.go:171","msg":"trace[1583250631] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"824.438596ms","start":"2026-07-30T21:01:37.424114Z","end":"2026-07-30T21:01:38.248553Z","steps":["trace[1583250631] 'process raft request' (duration: 824.185949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:38.248772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:37.424079Z","time spent":"824.59241ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:38.248761Z","caller":"traceutil/trace.go:171","msg":"trace[272252292] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1443; }","duration":"743.841444ms","start":"2026-07-30T21:01:37.50488Z","end":"2026-07-30T21:01:38.248721Z","steps":["trace[272252292] 'read index received' (duration: 374.140047ms)","trace[272252292] 'applied index is now lower than readState.Index' (duration: 369.698857ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:38.2488Z","caller":"traceutil/trace.go:171","msg":"trace[536729105] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"479.736427ms","start":"2026-07-30T21:01:37.769023Z","end":"2026-07-30T21:01:38.24876Z","steps":["trace[536729105] 'process raft request' (duration: 479.487521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:38.248956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.067189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:38.249025Z","caller":"traceutil/trace.go:171","msg":"trace[814990745] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1395; }","duration":"744.204863ms","start":"2026-07-30T21:01:37.504803Z","end":"2026-07-30T21:01:38.249008Z","steps":["trace[814990745] 'agreement among raft nodes before linearized reading' (duration: 744.08131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:38.249031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.523011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:01:38.249065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:37.504774Z","time spent":"744.281535ms","remote":"127.0.0.1:59458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:01:38.249074Z","caller":"traceutil/trace.go:171","msg":"trace[563200330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1395; }","duration":"640.658864ms","start":"2026-07-30T21:01:37.608403Z","end":"2026-07-30T21:01:38.249062Z","steps":["trace[563200330] 'agreement among raft nodes before linearized reading' (duration: 640.528791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:38.249123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:37.608344Z","time spent":"640.770737ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:01:38.24911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.760106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T21:01:38.2492Z","caller":"traceutil/trace.go:171","msg":"trace[1941704850] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1395; }","duration":"235.90715ms","start":"2026-07-30T21:01:38.013268Z","end":"2026-07-30T21:01:38.249176Z","steps":["trace[1941704850] 'agreement among raft nodes before linearized reading' (duration: 235.674194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:38.249214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.425232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-30T21:01:38.249228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.373438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T21:01:38.249288Z","caller":"traceutil/trace.go:171","msg":"trace[1080466437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1395; }","duration":"259.537365ms","start":"2026-07-30T21:01:37.989726Z","end":"2026-07-30T21:01:38.249264Z","steps":["trace[1080466437] 'agreement among raft nodes before linearized reading' (duration: 259.290449ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:38.249293Z","caller":"traceutil/trace.go:171","msg":"trace[2058076802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1395; }","duration":"197.500242ms","start":"2026-07-30T21:01:38.051776Z","end":"2026-07-30T21:01:38.249276Z","steps":["trace[2058076802] 'agreement among raft nodes before linearized reading' (duration: 197.339358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:38.249384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.130911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:01:38.249548Z","caller":"traceutil/trace.go:171","msg":"trace[1639969472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1395; }","duration":"237.323745ms","start":"2026-07-30T21:01:38.012207Z","end":"2026-07-30T21:01:38.249531Z","steps":["trace[1639969472] 'agreement among raft nodes before linearized reading' (duration: 236.747891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:38.249011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:37.768983Z","time spent":"479.924172ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:38.249871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.773421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T21:01:38.24994Z","caller":"traceutil/trace.go:171","msg":"trace[1630277249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1395; }","duration":"236.851113ms","start":"2026-07-30T21:01:38.013072Z","end":"2026-07-30T21:01:38.249923Z","steps":["trace[1630277249] 'agreement among raft nodes before linearized reading' (duration: 236.016782ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:38.39555Z","caller":"traceutil/trace.go:171","msg":"trace[270439298] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"141.042595ms","start":"2026-07-30T21:01:38.254476Z","end":"2026-07-30T21:01:38.395519Z","steps":["trace[270439298] 'process raft request' (duration: 78.392417ms)","trace[270439298] 'compare' (duration: 62.436683ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:38.395778Z","caller":"traceutil/trace.go:171","msg":"trace[1748371514] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"141.177759ms","start":"2026-07-30T21:01:38.254575Z","end":"2026-07-30T21:01:38.395753Z","steps":["trace[1748371514] 'process raft request' (duration: 140.892742ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:38.396041Z","caller":"traceutil/trace.go:171","msg":"trace[823842608] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"141.424114ms","start":"2026-07-30T21:01:38.254589Z","end":"2026-07-30T21:01:38.396013Z","steps":["trace[823842608] 'process raft request' (duration: 141.074456ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:38.396151Z","caller":"traceutil/trace.go:171","msg":"trace[1414089859] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"141.549618ms","start":"2026-07-30T21:01:38.254574Z","end":"2026-07-30T21:01:38.396124Z","steps":["trace[1414089859] 'process raft request' (duration: 141.20356ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:39.448183Z","caller":"traceutil/trace.go:171","msg":"trace[1741704785] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"319.114196ms","start":"2026-07-30T21:01:39.12905Z","end":"2026-07-30T21:01:39.448164Z","steps":["trace[1741704785] 'process raft request' (duration: 303.160072ms)","trace[1741704785] 'compare' (duration: 14.764644ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:39.448301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:39.129033Z","time spent":"319.213679ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:01:39.448128Z","caller":"traceutil/trace.go:171","msg":"trace[1843242003] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1454; }","duration":"316.8392ms","start":"2026-07-30T21:01:39.131267Z","end":"2026-07-30T21:01:39.448106Z","steps":["trace[1843242003] 'read index received' (duration: 300.950417ms)","trace[1843242003] 'applied index is now lower than readState.Index' (duration: 15.887943ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:39.448512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.22486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:39.448619Z","caller":"traceutil/trace.go:171","msg":"trace[683655987] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1404; }","duration":"317.399324ms","start":"2026-07-30T21:01:39.131191Z","end":"2026-07-30T21:01:39.44859Z","steps":["trace[683655987] 'agreement among raft nodes before linearized reading' (duration: 317.133607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:39.448683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:39.131164Z","time spent":"317.502567ms","remote":"127.0.0.1:54566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-07-30T21:01:39.455325Z","caller":"traceutil/trace.go:171","msg":"trace[1284746058] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"308.087915ms","start":"2026-07-30T21:01:39.147219Z","end":"2026-07-30T21:01:39.455307Z","steps":["trace[1284746058] 'process raft request' (duration: 307.718206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:39.455732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:39.147202Z","time spent":"308.173896ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:39.84112Z","caller":"traceutil/trace.go:171","msg":"trace[1361235239] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"348.352991ms","start":"2026-07-30T21:01:39.492737Z","end":"2026-07-30T21:01:39.84109Z","steps":["trace[1361235239] 'process raft request' (duration: 320.352238ms)","trace[1361235239] 'compare' (duration: 27.752547ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:39.841216Z","caller":"traceutil/trace.go:171","msg":"trace[945671784] linearizableReadLoop","detail":"{readStateIndex:1459; appliedIndex:1458; }","duration":"233.83416ms","start":"2026-07-30T21:01:39.607349Z","end":"2026-07-30T21:01:39.841183Z","steps":["trace[945671784] 'read index received' (duration: 205.759456ms)","trace[945671784] 'applied index is now lower than readState.Index' (duration: 28.071464ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:39.841289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:39.492718Z","time spent":"348.478074ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:39.841351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.987063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:39.841477Z","caller":"traceutil/trace.go:171","msg":"trace[1592677138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1409; }","duration":"234.162147ms","start":"2026-07-30T21:01:39.607294Z","end":"2026-07-30T21:01:39.841456Z","steps":["trace[1592677138] 'agreement among raft nodes before linearized reading' (duration: 233.986743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:39.841422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.843893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:39.841605Z","caller":"traceutil/trace.go:171","msg":"trace[1049016216] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1409; }","duration":"188.046328ms","start":"2026-07-30T21:01:39.653542Z","end":"2026-07-30T21:01:39.841588Z","steps":["trace[1049016216] 'agreement among raft nodes before linearized reading' (duration: 187.805532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:40.649907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.793259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:01:40.650018Z","caller":"traceutil/trace.go:171","msg":"trace[9310253] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1461; }","duration":"249.692631ms","start":"2026-07-30T21:01:40.400308Z","end":"2026-07-30T21:01:40.650001Z","steps":["trace[9310253] 'read index received' (duration: 68.664387ms)","trace[9310253] 'applied index is now lower than readState.Index' (duration: 181.026854ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:40.650335Z","caller":"traceutil/trace.go:171","msg":"trace[673025569] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"300.292121ms","start":"2026-07-30T21:01:40.350027Z","end":"2026-07-30T21:01:40.650319Z","steps":["trace[673025569] 'process raft request' (duration: 118.970709ms)","trace[673025569] 'compare' (duration: 180.550263ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:40.6504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.07567ms","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":"warn","ts":"2026-07-30T21:01:40.650413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:40.349992Z","time spent":"300.383643ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:40.650455Z","caller":"traceutil/trace.go:171","msg":"trace[1456310971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1411; }","duration":"250.183063ms","start":"2026-07-30T21:01:40.40026Z","end":"2026-07-30T21:01:40.650443Z","steps":["trace[1456310971] 'agreement among raft nodes before linearized reading' (duration: 250.002579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:40.65045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.010609ms","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-07-30T21:01:40.650485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.63824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-30T21:01:40.650485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.773503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-30T21:01:40.650559Z","caller":"traceutil/trace.go:171","msg":"trace[1903108856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1411; }","duration":"250.182713ms","start":"2026-07-30T21:01:40.400353Z","end":"2026-07-30T21:01:40.650536Z","steps":["trace[1903108856] 'agreement among raft nodes before linearized reading' (duration: 249.912477ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:40.650577Z","caller":"traceutil/trace.go:171","msg":"trace[977567651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1411; }","duration":"249.782024ms","start":"2026-07-30T21:01:40.400772Z","end":"2026-07-30T21:01:40.650554Z","steps":["trace[977567651] 'agreement among raft nodes before linearized reading' (duration: 249.501647ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:40.650577Z","caller":"traceutil/trace.go:171","msg":"trace[1455172132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1411; }","duration":"249.897896ms","start":"2026-07-30T21:01:40.400662Z","end":"2026-07-30T21:01:40.65056Z","steps":["trace[1455172132] 'agreement among raft nodes before linearized reading' (duration: 249.621779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:41.228434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.584038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:01:41.228613Z","caller":"traceutil/trace.go:171","msg":"trace[797574048] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"573.3957ms","start":"2026-07-30T21:01:40.655184Z","end":"2026-07-30T21:01:41.22858Z","steps":["trace[797574048] 'process raft request' (duration: 183.469664ms)","trace[797574048] 'compare' (duration: 389.379043ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:41.228686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:40.655155Z","time spent":"573.496133ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:42.211377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.797473ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:42.211541Z","caller":"traceutil/trace.go:171","msg":"trace[1748421036] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1412; }","duration":"217.976197ms","start":"2026-07-30T21:01:41.993542Z","end":"2026-07-30T21:01:42.211519Z","steps":["trace[1748421036] 'range keys from in-memory index tree' (duration: 217.777803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:42.212282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.94272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:01:42.212539Z","caller":"traceutil/trace.go:171","msg":"trace[652052758] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"1.557097313s","start":"2026-07-30T21:01:40.655409Z","end":"2026-07-30T21:01:42.212506Z","steps":["trace[652052758] 'process raft request' (duration: 686.778343ms)","trace[652052758] 'compare' (duration: 868.859094ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:42.212722Z","caller":"traceutil/trace.go:171","msg":"trace[1028188003] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"1.556437837s","start":"2026-07-30T21:01:40.656261Z","end":"2026-07-30T21:01:42.212699Z","steps":["trace[1028188003] 'process raft request' (duration: 1.556335785s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:42.21279Z","caller":"traceutil/trace.go:171","msg":"trace[898453093] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"1.557170095s","start":"2026-07-30T21:01:40.655597Z","end":"2026-07-30T21:01:42.212767Z","steps":["trace[898453093] 'process raft request' (duration: 1.556880638s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:42.212822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:40.656239Z","time spent":"1.55653946s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:42.212864Z","caller":"traceutil/trace.go:171","msg":"trace[1269749747] linearizableReadLoop","detail":"{readStateIndex:1467; appliedIndex:1463; }","duration":"1.200330167s","start":"2026-07-30T21:01:41.012513Z","end":"2026-07-30T21:01:42.212843Z","steps":["trace[1269749747] 'read index received' (duration: 329.640608ms)","trace[1269749747] 'applied index is now lower than readState.Index' (duration: 870.687779ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:42.212956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:40.655591Z","time spent":"1.557258228s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:42.212982Z","caller":"traceutil/trace.go:171","msg":"trace[1291870619] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"1.385992734s","start":"2026-07-30T21:01:40.826956Z","end":"2026-07-30T21:01:42.212949Z","steps":["trace[1291870619] 'process raft request' (duration: 1.385688547s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:42.213013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.493515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:42.213063Z","caller":"traceutil/trace.go:171","msg":"trace[1638311707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1416; }","duration":"605.577507ms","start":"2026-07-30T21:01:41.607472Z","end":"2026-07-30T21:01:42.213049Z","steps":["trace[1638311707] 'agreement among raft nodes before linearized reading' (duration: 605.504075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:42.213105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:41.60745Z","time spent":"605.648288ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:01:42.213128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:40.826917Z","time spent":"1.386135518s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:42.213176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200645224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-07-30T21:01:42.213178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:40.655388Z","time spent":"1.557680767s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:42.213261Z","caller":"traceutil/trace.go:171","msg":"trace[2120568490] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1416; }","duration":"1.200788747s","start":"2026-07-30T21:01:41.012454Z","end":"2026-07-30T21:01:42.213242Z","steps":["trace[2120568490] 'agreement among raft nodes before linearized reading' (duration: 1.200527071s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:42.213308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:41.012425Z","time spent":"1.20087506s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T21:01:42.688859Z","caller":"traceutil/trace.go:171","msg":"trace[1626168060] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"468.594252ms","start":"2026-07-30T21:01:42.220241Z","end":"2026-07-30T21:01:42.688835Z","steps":["trace[1626168060] 'process raft request' (duration: 456.169074ms)","trace[1626168060] 'compare' (duration: 12.10297ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:42.689006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:42.220219Z","time spent":"468.710706ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:42.689084Z","caller":"traceutil/trace.go:171","msg":"trace[1626344997] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"339.515242ms","start":"2026-07-30T21:01:42.349537Z","end":"2026-07-30T21:01:42.689052Z","steps":["trace[1626344997] 'process raft request' (duration: 339.05619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:42.689181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:42.349511Z","time spent":"339.619064ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:42.718718Z","caller":"traceutil/trace.go:171","msg":"trace[1956595943] linearizableReadLoop","detail":"{readStateIndex:1470; appliedIndex:1467; }","duration":"111.128137ms","start":"2026-07-30T21:01:42.607565Z","end":"2026-07-30T21:01:42.718693Z","steps":["trace[1956595943] 'read index received' (duration: 68.865852ms)","trace[1956595943] 'applied index is now lower than readState.Index' (duration: 42.260755ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:42.719134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.527316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:42.719355Z","caller":"traceutil/trace.go:171","msg":"trace[1572260279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1418; }","duration":"111.813374ms","start":"2026-07-30T21:01:42.607506Z","end":"2026-07-30T21:01:42.71932Z","steps":["trace[1572260279] 'agreement among raft nodes before linearized reading' (duration: 111.455454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:44.891326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.471773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:44.891419Z","caller":"traceutil/trace.go:171","msg":"trace[1571863978] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1443; }","duration":"344.635058ms","start":"2026-07-30T21:01:44.546761Z","end":"2026-07-30T21:01:44.891396Z","steps":["trace[1571863978] 'count revisions from in-memory index tree' (duration: 344.251398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:44.891418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.145862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:01:44.891465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:44.546728Z","time spent":"344.72499ms","remote":"127.0.0.1:59414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:01:44.891521Z","caller":"traceutil/trace.go:171","msg":"trace[529142985] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1443; }","duration":"409.350996ms","start":"2026-07-30T21:01:44.482145Z","end":"2026-07-30T21:01:44.891496Z","steps":["trace[529142985] 'count revisions from in-memory index tree' (duration: 408.946337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:44.891605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:44.482104Z","time spent":"409.471899ms","remote":"127.0.0.1:53684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:01:44.891748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.37832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-30T21:01:44.891817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.760596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:01:44.891799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.220987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T21:01:44.891851Z","caller":"traceutil/trace.go:171","msg":"trace[1877211668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1443; }","duration":"673.533364ms","start":"2026-07-30T21:01:44.218292Z","end":"2026-07-30T21:01:44.891825Z","steps":["trace[1877211668] 'range keys from in-memory index tree' (duration: 673.122574ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:44.891886Z","caller":"traceutil/trace.go:171","msg":"trace[446132238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1443; }","duration":"673.380401ms","start":"2026-07-30T21:01:44.218485Z","end":"2026-07-30T21:01:44.891866Z","steps":["trace[446132238] 'range keys from in-memory index tree' (duration: 673.008422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:44.891894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.432997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:01:44.891916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:44.218278Z","time spent":"673.628267ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T21:01:44.891935Z","caller":"traceutil/trace.go:171","msg":"trace[1110520692] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1443; }","duration":"487.521919ms","start":"2026-07-30T21:01:44.4044Z","end":"2026-07-30T21:01:44.891922Z","steps":["trace[1110520692] 'count revisions from in-memory index tree' (duration: 487.342813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:44.891934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:44.218456Z","time spent":"673.468763ms","remote":"127.0.0.1:54708","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-07-30T21:01:44.891971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:44.404345Z","time spent":"487.61518ms","remote":"127.0.0.1:59356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:01:44.891952Z","caller":"traceutil/trace.go:171","msg":"trace[1585663823] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1495; }","duration":"162.104487ms","start":"2026-07-30T21:01:44.729817Z","end":"2026-07-30T21:01:44.891922Z","steps":["trace[1585663823] 'read index received' (duration: 158.275832ms)","trace[1585663823] 'applied index is now lower than readState.Index' (duration: 3.826764ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:44.891862Z","caller":"traceutil/trace.go:171","msg":"trace[781738458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1443; }","duration":"672.843088ms","start":"2026-07-30T21:01:44.219007Z","end":"2026-07-30T21:01:44.891851Z","steps":["trace[781738458] 'range keys from in-memory index tree' (duration: 672.475988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:44.89207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:44.218991Z","time spent":"673.070583ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T21:01:44.892172Z","caller":"traceutil/trace.go:171","msg":"trace[1986444779] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"196.994058ms","start":"2026-07-30T21:01:44.695166Z","end":"2026-07-30T21:01:44.89216Z","steps":["trace[1986444779] 'process raft request' (duration: 192.913918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:44.892759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.869225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:01:44.89289Z","caller":"traceutil/trace.go:171","msg":"trace[1793112699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1444; }","duration":"163.1159ms","start":"2026-07-30T21:01:44.729747Z","end":"2026-07-30T21:01:44.892863Z","steps":["trace[1793112699] 'agreement among raft nodes before linearized reading' (duration: 162.546397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:58.116058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.943823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T21:01:58.116164Z","caller":"traceutil/trace.go:171","msg":"trace[197720555] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1580; }","duration":"109.127507ms","start":"2026-07-30T21:01:58.007015Z","end":"2026-07-30T21:01:58.116142Z","steps":["trace[197720555] 'range keys from in-memory index tree' (duration: 108.717957ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:59.367409Z","caller":"traceutil/trace.go:171","msg":"trace[603675082] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"400.437556ms","start":"2026-07-30T21:01:58.965509Z","end":"2026-07-30T21:01:59.365947Z","steps":["trace[603675082] 'process raft request' (duration: 400.256432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:59.368244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:58.965491Z","time spent":"402.668472ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:59.371994Z","caller":"traceutil/trace.go:171","msg":"trace[144567985] linearizableReadLoop","detail":"{readStateIndex:1649; appliedIndex:1648; }","duration":"405.766558ms","start":"2026-07-30T21:01:58.966205Z","end":"2026-07-30T21:01:59.371972Z","steps":["trace[144567985] 'read index received' (duration: 399.666788ms)","trace[144567985] 'applied index is now lower than readState.Index' (duration: 6.09802ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:59.372058Z","caller":"traceutil/trace.go:171","msg":"trace[689982504] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"405.858531ms","start":"2026-07-30T21:01:58.966183Z","end":"2026-07-30T21:01:59.372042Z","steps":["trace[689982504] 'process raft request' (duration: 405.668956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:59.372133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:58.966169Z","time spent":"405.926182ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:59.37221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.981833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:01:59.372245Z","caller":"traceutil/trace.go:171","msg":"trace[560650654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1590; }","duration":"406.047686ms","start":"2026-07-30T21:01:58.966186Z","end":"2026-07-30T21:01:59.372233Z","steps":["trace[560650654] 'agreement among raft nodes before linearized reading' (duration: 405.896792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:59.372271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:58.966174Z","time spent":"406.091026ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:01:59.372496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.497177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T21:01:59.372542Z","caller":"traceutil/trace.go:171","msg":"trace[68196314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1590; }","duration":"404.564409ms","start":"2026-07-30T21:01:58.967966Z","end":"2026-07-30T21:01:59.372531Z","steps":["trace[68196314] 'agreement among raft nodes before linearized reading' (duration: 404.403275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:59.372575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:58.967955Z","time spent":"404.6129ms","remote":"127.0.0.1:54708","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-07-30T21:01:59.372646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.959108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-bwkt8\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-07-30T21:01:59.372678Z","caller":"traceutil/trace.go:171","msg":"trace[1250455720] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-bwkt8; range_end:; response_count:1; response_revision:1590; }","duration":"148.026719ms","start":"2026-07-30T21:01:59.224642Z","end":"2026-07-30T21:01:59.372669Z","steps":["trace[1250455720] 'agreement among raft nodes before linearized reading' (duration: 147.947177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:05.198513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.299423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:02:05.198639Z","caller":"traceutil/trace.go:171","msg":"trace[1906707964] linearizableReadLoop","detail":"{readStateIndex:1775; appliedIndex:1774; }","duration":"207.99312ms","start":"2026-07-30T21:02:04.990628Z","end":"2026-07-30T21:02:05.198621Z","steps":["trace[1906707964] 'read index received' (duration: 32.462551ms)","trace[1906707964] 'applied index is now lower than readState.Index' (duration: 175.529429ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:02:05.198706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.080343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:02:05.198736Z","caller":"traceutil/trace.go:171","msg":"trace[1519265492] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1715; }","duration":"208.145714ms","start":"2026-07-30T21:02:04.990583Z","end":"2026-07-30T21:02:05.198728Z","steps":["trace[1519265492] 'agreement among raft nodes before linearized reading' (duration: 208.085442ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:05.198877Z","caller":"traceutil/trace.go:171","msg":"trace[1348621361] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"230.813094ms","start":"2026-07-30T21:02:04.968022Z","end":"2026-07-30T21:02:05.198835Z","steps":["trace[1348621361] 'process raft request' (duration: 55.102661ms)","trace[1348621361] 'compare' (duration: 175.16574ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:05.432894Z","caller":"traceutil/trace.go:171","msg":"trace[1979546790] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"155.758629ms","start":"2026-07-30T21:02:05.27708Z","end":"2026-07-30T21:02:05.432838Z","steps":["trace[1979546790] 'process raft request' (duration: 155.550434ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:28.40634Z","caller":"traceutil/trace.go:171","msg":"trace[1457590328] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"106.580035ms","start":"2026-07-30T21:02:28.299721Z","end":"2026-07-30T21:02:28.406301Z","steps":["trace[1457590328] 'process raft request' (duration: 106.089573ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:29.299657Z","caller":"traceutil/trace.go:171","msg":"trace[90952234] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"366.859427ms","start":"2026-07-30T21:02:28.932769Z","end":"2026-07-30T21:02:29.299628Z","steps":["trace[90952234] 'process raft request' (duration: 366.634321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:29.299844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:28.932712Z","time spent":"367.028531ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:02:29.304742Z","caller":"traceutil/trace.go:171","msg":"trace[121634563] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"139.471967ms","start":"2026-07-30T21:02:29.165217Z","end":"2026-07-30T21:02:29.304689Z","steps":["trace[121634563] 'process raft request' (duration: 139.294433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:29.531449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.874215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:02:29.531619Z","caller":"traceutil/trace.go:171","msg":"trace[222796557] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"168.798508ms","start":"2026-07-30T21:02:29.362784Z","end":"2026-07-30T21:02:29.531582Z","steps":["trace[222796557] 'process raft request' (duration: 32.688918ms)","trace[222796557] 'compare' (duration: 135.73246ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:02:30.108187Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767087894418943,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:02:30.382733Z","caller":"traceutil/trace.go:171","msg":"trace[1628276073] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"775.306364ms","start":"2026-07-30T21:02:29.60738Z","end":"2026-07-30T21:02:30.382686Z","steps":["trace[1628276073] 'read index received' (duration: 764.113508ms)","trace[1628276073] 'applied index is now lower than readState.Index' (duration: 11.190656ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:30.382923Z","caller":"traceutil/trace.go:171","msg":"trace[236294170] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"781.932647ms","start":"2026-07-30T21:02:29.600971Z","end":"2026-07-30T21:02:30.382904Z","steps":["trace[236294170] 'process raft request' (duration: 770.626049ms)","trace[236294170] 'compare' (duration: 10.53634ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:02:30.383074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:29.600946Z","time spent":"782.00656ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:02:30.383288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.08944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T21:02:30.383404Z","caller":"traceutil/trace.go:171","msg":"trace[1053171989] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2011; }","duration":"758.281715ms","start":"2026-07-30T21:02:29.625097Z","end":"2026-07-30T21:02:30.383379Z","steps":["trace[1053171989] 'agreement among raft nodes before linearized reading' (duration: 757.931896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:30.383434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.058863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:02:30.383456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:29.625066Z","time spent":"758.379718ms","remote":"127.0.0.1:54708","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":"info","ts":"2026-07-30T21:02:30.38349Z","caller":"traceutil/trace.go:171","msg":"trace[1795185832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2011; }","duration":"776.150214ms","start":"2026-07-30T21:02:29.607325Z","end":"2026-07-30T21:02:30.383476Z","steps":["trace[1795185832] 'agreement among raft nodes before linearized reading' (duration: 776.044762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:30.383526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:29.607308Z","time spent":"776.210666ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:02:30.383524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.385159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"warn","ts":"2026-07-30T21:02:30.38352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.580294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T21:02:30.383563Z","caller":"traceutil/trace.go:171","msg":"trace[1828639765] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2011; }","duration":"389.469311ms","start":"2026-07-30T21:02:29.99408Z","end":"2026-07-30T21:02:30.383549Z","steps":["trace[1828639765] 'agreement among raft nodes before linearized reading' (duration: 389.307207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:30.383584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.659768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-30T21:02:30.383601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:29.99405Z","time spent":"389.543123ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T21:02:30.383612Z","caller":"traceutil/trace.go:171","msg":"trace[1335564521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2011; }","duration":"739.694086ms","start":"2026-07-30T21:02:29.643892Z","end":"2026-07-30T21:02:30.383586Z","steps":["trace[1335564521] 'agreement among raft nodes before linearized reading' (duration: 739.162513ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:30.383661Z","caller":"traceutil/trace.go:171","msg":"trace[1351080425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2011; }","duration":"757.735921ms","start":"2026-07-30T21:02:29.625887Z","end":"2026-07-30T21:02:30.383623Z","steps":["trace[1351080425] 'agreement among raft nodes before linearized reading' (duration: 757.485415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:30.383688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:29.643885Z","time spent":"739.791668ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T21:02:30.383703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:29.625878Z","time spent":"757.816593ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T21:02:30.383683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.918781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:02:30.383808Z","caller":"traceutil/trace.go:171","msg":"trace[706337105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2011; }","duration":"740.086026ms","start":"2026-07-30T21:02:29.643692Z","end":"2026-07-30T21:02:30.383778Z","steps":["trace[706337105] 'agreement among raft nodes before linearized reading' (duration: 739.678086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:30.383865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:29.643679Z","time spent":"740.175858ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:02:33.516903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.303365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-30T21:02:33.517003Z","caller":"traceutil/trace.go:171","msg":"trace[937721730] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2030; }","duration":"962.452688ms","start":"2026-07-30T21:02:32.55452Z","end":"2026-07-30T21:02:33.516973Z","steps":["trace[937721730] 'range keys from in-memory index tree' (duration: 962.091029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:33.51705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:32.554501Z","time spent":"962.53653ms","remote":"127.0.0.1:40780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-30T21:02:33.613044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.794815ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-30T21:02:33.613349Z","caller":"traceutil/trace.go:171","msg":"trace[1824461695] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"296.034019ms","start":"2026-07-30T21:02:33.317292Z","end":"2026-07-30T21:02:33.613326Z","steps":["trace[1824461695] 'process raft request' (duration: 295.879995ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:33.613347Z","caller":"traceutil/trace.go:171","msg":"trace[2053113139] linearizableReadLoop","detail":"{readStateIndex:2100; appliedIndex:2099; }","duration":"1.007321965s","start":"2026-07-30T21:02:32.605968Z","end":"2026-07-30T21:02:33.61329Z","steps":["trace[2053113139] 'read index received' (duration: 285.450018ms)","trace[2053113139] 'applied index is now lower than readState.Index' (duration: 721.867127ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:02:33.613591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007595041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:02:33.613702Z","caller":"traceutil/trace.go:171","msg":"trace[238845103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2031; }","duration":"1.007755885s","start":"2026-07-30T21:02:32.605922Z","end":"2026-07-30T21:02:33.613678Z","steps":["trace[238845103] 'agreement among raft nodes before linearized reading' (duration: 1.00753311s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:33.613767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:32.605904Z","time spent":"1.007854627s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:02:33.628279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.970599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T21:02:33.628291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.965979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-30T21:02:33.628352Z","caller":"traceutil/trace.go:171","msg":"trace[893115843] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2032; }","duration":"563.096842ms","start":"2026-07-30T21:02:33.065236Z","end":"2026-07-30T21:02:33.628333Z","steps":["trace[893115843] 'agreement among raft nodes before linearized reading' (duration: 562.99404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:33.628351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.413602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-30T21:02:33.628402Z","caller":"traceutil/trace.go:171","msg":"trace[2134377844] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2032; }","duration":"838.132872ms","start":"2026-07-30T21:02:32.790252Z","end":"2026-07-30T21:02:33.628385Z","steps":["trace[2134377844] 'agreement among raft nodes before linearized reading' (duration: 837.881596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:33.628418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:33.06517Z","time spent":"563.231936ms","remote":"127.0.0.1:54622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:02:33.628425Z","caller":"traceutil/trace.go:171","msg":"trace[1440390456] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2032; }","duration":"112.565465ms","start":"2026-07-30T21:02:33.515837Z","end":"2026-07-30T21:02:33.628402Z","steps":["trace[1440390456] 'agreement among raft nodes before linearized reading' (duration: 112.316859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:33.628445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:32.790223Z","time spent":"838.212434ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T21:02:33.628687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12233"} {"level":"info","ts":"2026-07-30T21:02:33.628774Z","caller":"traceutil/trace.go:171","msg":"trace[1391118694] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2032; }","duration":"857.628993ms","start":"2026-07-30T21:02:32.771127Z","end":"2026-07-30T21:02:33.628756Z","steps":["trace[1391118694] 'agreement among raft nodes before linearized reading' (duration: 857.332186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:33.628816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:32.771092Z","time spent":"857.716797ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12256,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-30T21:02:45.922657Z","caller":"traceutil/trace.go:171","msg":"trace[452629740] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"256.225636ms","start":"2026-07-30T21:02:45.6664Z","end":"2026-07-30T21:02:45.922626Z","steps":["trace[452629740] 'process raft request' (duration: 191.225844ms)","trace[452629740] 'compare' (duration: 64.876439ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:46.587842Z","caller":"traceutil/trace.go:171","msg":"trace[1821323979] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"118.228484ms","start":"2026-07-30T21:02:46.469578Z","end":"2026-07-30T21:02:46.587807Z","steps":["trace[1821323979] 'process raft request' (duration: 118.127262ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:46.587874Z","caller":"traceutil/trace.go:171","msg":"trace[1491414549] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"118.267325ms","start":"2026-07-30T21:02:46.46956Z","end":"2026-07-30T21:02:46.587828Z","steps":["trace[1491414549] 'process raft request' (duration: 86.539333ms)","trace[1491414549] 'compare' (duration: 31.429135ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:46.588722Z","caller":"traceutil/trace.go:171","msg":"trace[1294772503] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"117.555618ms","start":"2026-07-30T21:02:46.471148Z","end":"2026-07-30T21:02:46.588704Z","steps":["trace[1294772503] 'process raft request' (duration: 116.614664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.012767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767087894419217,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:02:49.51369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767087894419217,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:02:49.6944Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.955734816s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T21:02:49.695147Z","caller":"traceutil/trace.go:171","msg":"trace[1857979160] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"1.956660459s","start":"2026-07-30T21:02:47.738446Z","end":"2026-07-30T21:02:49.695107Z","steps":["trace[1857979160] 'process raft request' (duration: 1.956386053s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.695418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:47.738405Z","time spent":"1.956867334s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:02:49.723636Z","caller":"traceutil/trace.go:171","msg":"trace[1106762779] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2194; }","duration":"1.2111653s","start":"2026-07-30T21:02:48.512426Z","end":"2026-07-30T21:02:49.723591Z","steps":["trace[1106762779] 'read index received' (duration: 1.18236029s)","trace[1106762779] 'applied index is now lower than readState.Index' (duration: 28.80386ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:49.723675Z","caller":"traceutil/trace.go:171","msg":"trace[2146773169] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"1.794799458s","start":"2026-07-30T21:02:47.928828Z","end":"2026-07-30T21:02:49.723628Z","steps":["trace[2146773169] 'process raft request' (duration: 1.794561463s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:49.72376Z","caller":"traceutil/trace.go:171","msg":"trace[434160093] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"583.198828ms","start":"2026-07-30T21:02:49.140548Z","end":"2026-07-30T21:02:49.723746Z","steps":["trace[434160093] 'process raft request' (duration: 582.994243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.723894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:47.928802Z","time spent":"1.794974403s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:02:49.723911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:49.14051Z","time spent":"583.28456ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:02:49.724421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130689256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T21:02:49.724486Z","caller":"traceutil/trace.go:171","msg":"trace[1361704090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2124; }","duration":"1.130773058s","start":"2026-07-30T21:02:48.5937Z","end":"2026-07-30T21:02:49.724473Z","steps":["trace[1361704090] 'agreement among raft nodes before linearized reading' (duration: 1.130520112s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.724483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130740457s","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":"warn","ts":"2026-07-30T21:02:49.72417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104834748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-30T21:02:49.724198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211773205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:02:49.724655Z","caller":"traceutil/trace.go:171","msg":"trace[529675812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2124; }","duration":"1.21235806s","start":"2026-07-30T21:02:48.512272Z","end":"2026-07-30T21:02:49.72463Z","steps":["trace[529675812] 'agreement among raft nodes before linearized reading' (duration: 1.211596071s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.724027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131719701s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T21:02:49.724805Z","caller":"traceutil/trace.go:171","msg":"trace[1499249057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2124; }","duration":"1.132550932s","start":"2026-07-30T21:02:48.592238Z","end":"2026-07-30T21:02:49.724789Z","steps":["trace[1499249057] 'agreement among raft nodes before linearized reading' (duration: 1.131634939s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.724886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.59221Z","time spent":"1.132658313s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T21:02:49.72456Z","caller":"traceutil/trace.go:171","msg":"trace[1615526204] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2124; }","duration":"1.13083579s","start":"2026-07-30T21:02:48.593707Z","end":"2026-07-30T21:02:49.724543Z","steps":["trace[1615526204] 'agreement among raft nodes before linearized reading' (duration: 1.130601144s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.725043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.593698Z","time spent":"1.131317141s","remote":"127.0.0.1:54708","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-07-30T21:02:49.72416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085997684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-30T21:02:49.725212Z","caller":"traceutil/trace.go:171","msg":"trace[747338639] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2124; }","duration":"1.08708094s","start":"2026-07-30T21:02:48.63811Z","end":"2026-07-30T21:02:49.725191Z","steps":["trace[747338639] 'agreement among raft nodes before linearized reading' (duration: 1.08584886s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.725269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.638091Z","time spent":"1.087164502s","remote":"127.0.0.1:40780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-30T21:02:49.724281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117245634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:02:49.725501Z","caller":"traceutil/trace.go:171","msg":"trace[2111909900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2124; }","duration":"1.118478815s","start":"2026-07-30T21:02:48.607009Z","end":"2026-07-30T21:02:49.725488Z","steps":["trace[2111909900] 'agreement among raft nodes before linearized reading' (duration: 1.117196153s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.725549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.606999Z","time spent":"1.118539947s","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:02:49.724267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062051453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-30T21:02:49.72572Z","caller":"traceutil/trace.go:171","msg":"trace[1729923422] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2124; }","duration":"1.063570362s","start":"2026-07-30T21:02:48.662132Z","end":"2026-07-30T21:02:49.725702Z","steps":["trace[1729923422] 'agreement among raft nodes before linearized reading' (duration: 1.06190526s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.725728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.512084Z","time spent":"1.213615481s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:02:49.725786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.662101Z","time spent":"1.063666873s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T21:02:49.724329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.235468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-30T21:02:49.7259Z","caller":"traceutil/trace.go:171","msg":"trace[1278234692] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2124; }","duration":"371.854108ms","start":"2026-07-30T21:02:49.354033Z","end":"2026-07-30T21:02:49.725887Z","steps":["trace[1278234692] 'agreement among raft nodes before linearized reading' (duration: 370.182807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.725933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:49.354003Z","time spent":"371.91951ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T21:02:49.724373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064579955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-30T21:02:49.726029Z","caller":"traceutil/trace.go:171","msg":"trace[2053981803] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2124; }","duration":"1.066318219s","start":"2026-07-30T21:02:48.659687Z","end":"2026-07-30T21:02:49.726006Z","steps":["trace[2053981803] 'agreement among raft nodes before linearized reading' (duration: 1.064398331s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.724402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131928376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:02:49.724521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.593686Z","time spent":"1.130828289s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T21:02:49.724564Z","caller":"traceutil/trace.go:171","msg":"trace[437238977] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2124; }","duration":"1.105292849s","start":"2026-07-30T21:02:48.61926Z","end":"2026-07-30T21:02:49.724553Z","steps":["trace[437238977] 'agreement among raft nodes before linearized reading' (duration: 1.104805227s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.724152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000306111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12630"} {"level":"info","ts":"2026-07-30T21:02:49.726137Z","caller":"traceutil/trace.go:171","msg":"trace[1387921475] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2124; }","duration":"1.002318981s","start":"2026-07-30T21:02:48.723807Z","end":"2026-07-30T21:02:49.726126Z","steps":["trace[1387921475] 'agreement among raft nodes before linearized reading' (duration: 1.000217199s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:49.726185Z","caller":"traceutil/trace.go:171","msg":"trace[786055924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2124; }","duration":"1.133732361s","start":"2026-07-30T21:02:48.592436Z","end":"2026-07-30T21:02:49.726169Z","steps":["trace[786055924] 'agreement among raft nodes before linearized reading' (duration: 1.131835624s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.726225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.592418Z","time spent":"1.133796142s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:02:49.726224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.619249Z","time spent":"1.106961541s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T21:02:49.726231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.659652Z","time spent":"1.066559555s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T21:02:49.726257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.72379Z","time spent":"1.002416553s","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12653,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-30T21:02:49.860687Z","caller":"traceutil/trace.go:171","msg":"trace[740508957] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"129.710589ms","start":"2026-07-30T21:02:49.730928Z","end":"2026-07-30T21:02:49.860638Z","steps":["trace[740508957] 'process raft request' (duration: 75.515172ms)","trace[740508957] 'compare' (duration: 54.005972ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:49.919347Z","caller":"traceutil/trace.go:171","msg":"trace[139545583] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"188.267583ms","start":"2026-07-30T21:02:49.731045Z","end":"2026-07-30T21:02:49.919313Z","steps":["trace[139545583] 'process raft request' (duration: 187.985726ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:49.919687Z","caller":"traceutil/trace.go:171","msg":"trace[940260075] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"188.003475ms","start":"2026-07-30T21:02:49.73165Z","end":"2026-07-30T21:02:49.919654Z","steps":["trace[940260075] 'process raft request' (duration: 187.597235ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:49.919843Z","caller":"traceutil/trace.go:171","msg":"trace[1848467315] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"187.712448ms","start":"2026-07-30T21:02:49.732093Z","end":"2026-07-30T21:02:49.919805Z","steps":["trace[1848467315] 'process raft request' (duration: 187.579895ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:49.919889Z","caller":"traceutil/trace.go:171","msg":"trace[643506130] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"187.856182ms","start":"2026-07-30T21:02:49.731954Z","end":"2026-07-30T21:02:49.91981Z","steps":["trace[643506130] 'process raft request' (duration: 187.593205ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:49.920132Z","caller":"traceutil/trace.go:171","msg":"trace[596650142] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"187.964584ms","start":"2026-07-30T21:02:49.73214Z","end":"2026-07-30T21:02:49.920105Z","steps":["trace[596650142] 'process raft request' (duration: 187.594625ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:49.920747Z","caller":"traceutil/trace.go:171","msg":"trace[1335856523] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"188.257431ms","start":"2026-07-30T21:02:49.732471Z","end":"2026-07-30T21:02:49.920728Z","steps":["trace[1335856523] 'process raft request' (duration: 187.645556ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:49.921003Z","caller":"traceutil/trace.go:171","msg":"trace[1426014264] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"188.482566ms","start":"2026-07-30T21:02:49.732507Z","end":"2026-07-30T21:02:49.92099Z","steps":["trace[1426014264] 'process raft request' (duration: 188.066266ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:49.921039Z","caller":"traceutil/trace.go:171","msg":"trace[2115033869] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"188.045206ms","start":"2026-07-30T21:02:49.732972Z","end":"2026-07-30T21:02:49.921017Z","steps":["trace[2115033869] 'process raft request' (duration: 187.934173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.921213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.167804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:02:49.921293Z","caller":"traceutil/trace.go:171","msg":"trace[1599266925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2133; }","duration":"187.270166ms","start":"2026-07-30T21:02:49.73401Z","end":"2026-07-30T21:02:49.92128Z","steps":["trace[1599266925] 'agreement among raft nodes before linearized reading' (duration: 187.138303ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:49.921095Z","caller":"traceutil/trace.go:171","msg":"trace[983100772] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2197; }","duration":"186.937788ms","start":"2026-07-30T21:02:49.734017Z","end":"2026-07-30T21:02:49.920955Z","steps":["trace[983100772] 'read index received' (duration: 72.491896ms)","trace[983100772] 'applied index is now lower than readState.Index' (duration: 114.444502ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:02:49.921777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.253168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:02:49.921815Z","caller":"traceutil/trace.go:171","msg":"trace[1981832841] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2133; }","duration":"185.32487ms","start":"2026-07-30T21:02:49.736479Z","end":"2026-07-30T21:02:49.921804Z","steps":["trace[1981832841] 'agreement among raft nodes before linearized reading' (duration: 185.262679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:52.042684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.792612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:02:52.042838Z","caller":"traceutil/trace.go:171","msg":"trace[1438681286] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2210; }","duration":"300.55581ms","start":"2026-07-30T21:02:51.742259Z","end":"2026-07-30T21:02:52.042815Z","steps":["trace[1438681286] 'read index received' (duration: 30.39978ms)","trace[1438681286] 'applied index is now lower than readState.Index' (duration: 270.15411ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:52.042931Z","caller":"traceutil/trace.go:171","msg":"trace[1283828305] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"313.503099ms","start":"2026-07-30T21:02:51.729369Z","end":"2026-07-30T21:02:52.042872Z","steps":["trace[1283828305] 'process raft request' (duration: 200.350669ms)","trace[1283828305] 'compare' (duration: 112.617647ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:02:52.043175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.92061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-30T21:02:52.043198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:51.729333Z","time spent":"313.723674ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:02:52.043211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.4714ms","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":"warn","ts":"2026-07-30T21:02:52.043266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.65096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T21:02:52.043304Z","caller":"traceutil/trace.go:171","msg":"trace[754544168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2139; }","duration":"118.604384ms","start":"2026-07-30T21:02:51.924678Z","end":"2026-07-30T21:02:52.043282Z","steps":["trace[754544168] 'agreement among raft nodes before linearized reading' (duration: 118.329657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:52.043251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.528742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:02:52.043363Z","caller":"traceutil/trace.go:171","msg":"trace[1532347720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2139; }","duration":"119.823324ms","start":"2026-07-30T21:02:51.923519Z","end":"2026-07-30T21:02:52.043342Z","steps":["trace[1532347720] 'agreement among raft nodes before linearized reading' (duration: 119.512827ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:52.04341Z","caller":"traceutil/trace.go:171","msg":"trace[534825537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2139; }","duration":"118.765658ms","start":"2026-07-30T21:02:51.92463Z","end":"2026-07-30T21:02:52.043395Z","steps":["trace[534825537] 'agreement among raft nodes before linearized reading' (duration: 118.427269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:52.043407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.788909ms","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-07-30T21:02:52.043224Z","caller":"traceutil/trace.go:171","msg":"trace[1185390782] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2139; }","duration":"301.029192ms","start":"2026-07-30T21:02:51.742178Z","end":"2026-07-30T21:02:52.043208Z","steps":["trace[1185390782] 'agreement among raft nodes before linearized reading' (duration: 300.746225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:52.043444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.161188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-30T21:02:52.043544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:51.742147Z","time spent":"301.381621ms","remote":"127.0.0.1:40780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-30T21:02:52.043543Z","caller":"traceutil/trace.go:171","msg":"trace[780812501] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2139; }","duration":"204.921192ms","start":"2026-07-30T21:02:51.838548Z","end":"2026-07-30T21:02:52.043469Z","steps":["trace[780812501] 'agreement among raft nodes before linearized reading' (duration: 204.439041ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:52.043565Z","caller":"traceutil/trace.go:171","msg":"trace[1333969115] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2139; }","duration":"117.328233ms","start":"2026-07-30T21:02:51.926208Z","end":"2026-07-30T21:02:52.043537Z","steps":["trace[1333969115] 'agreement among raft nodes before linearized reading' (duration: 116.927653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:52.043622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.03639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:02:52.043753Z","caller":"traceutil/trace.go:171","msg":"trace[1406991066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2139; }","duration":"118.201034ms","start":"2026-07-30T21:02:51.925517Z","end":"2026-07-30T21:02:52.043718Z","steps":["trace[1406991066] 'agreement among raft nodes before linearized reading' (duration: 117.675932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:52.043622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.493071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T21:02:52.043821Z","caller":"traceutil/trace.go:171","msg":"trace[429162399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2139; }","duration":"178.805529ms","start":"2026-07-30T21:02:51.865002Z","end":"2026-07-30T21:02:52.043808Z","steps":["trace[429162399] 'agreement among raft nodes before linearized reading' (duration: 178.287476ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:52.202839Z","caller":"traceutil/trace.go:171","msg":"trace[296283291] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"153.883264ms","start":"2026-07-30T21:02:52.048932Z","end":"2026-07-30T21:02:52.202815Z","steps":["trace[296283291] 'process raft request' (duration: 96.954001ms)","trace[296283291] 'compare' (duration: 56.761449ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:52.247772Z","caller":"traceutil/trace.go:171","msg":"trace[305574901] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"198.491633ms","start":"2026-07-30T21:02:52.049261Z","end":"2026-07-30T21:02:52.247752Z","steps":["trace[305574901] 'process raft request' (duration: 198.283178ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:52.248035Z","caller":"traceutil/trace.go:171","msg":"trace[2136862286] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"198.608716ms","start":"2026-07-30T21:02:52.049389Z","end":"2026-07-30T21:02:52.247997Z","steps":["trace[2136862286] 'process raft request' (duration: 198.302509ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:52.248288Z","caller":"traceutil/trace.go:171","msg":"trace[1224616888] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"198.014613ms","start":"2026-07-30T21:02:52.050153Z","end":"2026-07-30T21:02:52.248168Z","steps":["trace[1224616888] 'process raft request' (duration: 197.771117ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:52.248424Z","caller":"traceutil/trace.go:171","msg":"trace[1400309846] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"197.91415ms","start":"2026-07-30T21:02:52.050427Z","end":"2026-07-30T21:02:52.248341Z","steps":["trace[1400309846] 'process raft request' (duration: 197.634243ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:52.248549Z","caller":"traceutil/trace.go:171","msg":"trace[2107664712] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"198.015332ms","start":"2026-07-30T21:02:52.050515Z","end":"2026-07-30T21:02:52.248531Z","steps":["trace[2107664712] 'process raft request' (duration: 197.746376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:52.75458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.781019ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-30T21:02:54.413383Z","caller":"traceutil/trace.go:171","msg":"trace[1394047282] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"350.779868ms","start":"2026-07-30T21:02:54.062572Z","end":"2026-07-30T21:02:54.413352Z","steps":["trace[1394047282] 'read index received' (duration: 350.581704ms)","trace[1394047282] 'applied index is now lower than readState.Index' (duration: 196.354µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:54.413442Z","caller":"traceutil/trace.go:171","msg":"trace[64892431] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"363.101602ms","start":"2026-07-30T21:02:54.050289Z","end":"2026-07-30T21:02:54.41339Z","steps":["trace[64892431] 'process raft request' (duration: 362.827776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:54.413646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:54.050257Z","time spent":"363.273827ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:02:54.413699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.43504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T21:02:54.413768Z","caller":"traceutil/trace.go:171","msg":"trace[849855712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2147; }","duration":"206.558832ms","start":"2026-07-30T21:02:54.20717Z","end":"2026-07-30T21:02:54.413729Z","steps":["trace[849855712] 'agreement among raft nodes before linearized reading' (duration: 206.372368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:54.413797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.199228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-30T21:02:54.413921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.21128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T21:02:54.413907Z","caller":"traceutil/trace.go:171","msg":"trace[612320452] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2147; }","duration":"351.376512ms","start":"2026-07-30T21:02:54.062506Z","end":"2026-07-30T21:02:54.413882Z","steps":["trace[612320452] 'agreement among raft nodes before linearized reading' (duration: 351.009903ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:54.413953Z","caller":"traceutil/trace.go:171","msg":"trace[307087296] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2147; }","duration":"160.262951ms","start":"2026-07-30T21:02:54.253682Z","end":"2026-07-30T21:02:54.413945Z","steps":["trace[307087296] 'agreement among raft nodes before linearized reading' (duration: 160.111547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:54.413974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:54.062476Z","time spent":"351.487165ms","remote":"127.0.0.1:40780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-30T21:02:54.413979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.574489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T21:02:54.414117Z","caller":"traceutil/trace.go:171","msg":"trace[1246264415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2147; }","duration":"160.715452ms","start":"2026-07-30T21:02:54.253328Z","end":"2026-07-30T21:02:54.414044Z","steps":["trace[1246264415] 'agreement among raft nodes before linearized reading' (duration: 160.432835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:54.414121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.202343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-30T21:02:54.414223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.340943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:02:54.414355Z","caller":"traceutil/trace.go:171","msg":"trace[1021990199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2147; }","duration":"160.569998ms","start":"2026-07-30T21:02:54.253756Z","end":"2026-07-30T21:02:54.414326Z","steps":["trace[1021990199] 'agreement among raft nodes before linearized reading' (duration: 160.127657ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:54.414272Z","caller":"traceutil/trace.go:171","msg":"trace[997155742] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2147; }","duration":"161.408878ms","start":"2026-07-30T21:02:54.252803Z","end":"2026-07-30T21:02:54.414212Z","steps":["trace[997155742] 'agreement among raft nodes before linearized reading' (duration: 161.011859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:54.414745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.003759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T21:02:54.414841Z","caller":"traceutil/trace.go:171","msg":"trace[51280349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2147; }","duration":"161.117111ms","start":"2026-07-30T21:02:54.253687Z","end":"2026-07-30T21:02:54.414804Z","steps":["trace[51280349] 'agreement among raft nodes before linearized reading' (duration: 159.953713ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:54.711098Z","caller":"traceutil/trace.go:171","msg":"trace[1396851877] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"291.931937ms","start":"2026-07-30T21:02:54.419121Z","end":"2026-07-30T21:02:54.711053Z","steps":["trace[1396851877] 'process raft request' (duration: 196.700929ms)","trace[1396851877] 'compare' (duration: 95.039404ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:54.778272Z","caller":"traceutil/trace.go:171","msg":"trace[1759551555] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"358.587381ms","start":"2026-07-30T21:02:54.419646Z","end":"2026-07-30T21:02:54.778234Z","steps":["trace[1759551555] 'process raft request' (duration: 358.316094ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:54.778276Z","caller":"traceutil/trace.go:171","msg":"trace[1899243383] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"358.5576ms","start":"2026-07-30T21:02:54.419685Z","end":"2026-07-30T21:02:54.778243Z","steps":["trace[1899243383] 'process raft request' (duration: 358.449157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:54.778489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:54.41966Z","time spent":"358.711574ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:02:54.778652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:54.419604Z","time spent":"358.754765ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:02:54.778989Z","caller":"traceutil/trace.go:171","msg":"trace[994123714] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"358.761835ms","start":"2026-07-30T21:02:54.420194Z","end":"2026-07-30T21:02:54.778956Z","steps":["trace[994123714] 'process raft request' (duration: 358.010897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:54.779195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:54.42017Z","time spent":"358.928969ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:02:54.779299Z","caller":"traceutil/trace.go:171","msg":"trace[78089897] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"359.014952ms","start":"2026-07-30T21:02:54.420257Z","end":"2026-07-30T21:02:54.779272Z","steps":["trace[78089897] 'process raft request' (duration: 358.546309ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:54.779385Z","caller":"traceutil/trace.go:171","msg":"trace[1879613905] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"358.234452ms","start":"2026-07-30T21:02:54.421088Z","end":"2026-07-30T21:02:54.779322Z","steps":["trace[1879613905] 'process raft request' (duration: 358.028706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:54.779494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.223497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:02:54.779531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:54.420233Z","time spent":"359.173356ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:02:54.779579Z","caller":"traceutil/trace.go:171","msg":"trace[1938774667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2153; }","duration":"172.37501ms","start":"2026-07-30T21:02:54.607187Z","end":"2026-07-30T21:02:54.779562Z","steps":["trace[1938774667] 'agreement among raft nodes before linearized reading' (duration: 172.183115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:54.7796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:54.421077Z","time spent":"358.403006ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:02:54.779288Z","caller":"traceutil/trace.go:171","msg":"trace[1044872324] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2221; }","duration":"171.99377ms","start":"2026-07-30T21:02:54.60726Z","end":"2026-07-30T21:02:54.779254Z","steps":["trace[1044872324] 'read index received' (duration: 8.580991ms)","trace[1044872324] 'applied index is now lower than readState.Index' (duration: 163.411299ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:15.89843Z","caller":"traceutil/trace.go:171","msg":"trace[1075757331] linearizableReadLoop","detail":"{readStateIndex:2353; appliedIndex:2352; }","duration":"381.831003ms","start":"2026-07-30T21:03:15.516563Z","end":"2026-07-30T21:03:15.898394Z","steps":["trace[1075757331] 'read index received' (duration: 381.519206ms)","trace[1075757331] 'applied index is now lower than readState.Index' (duration: 309.667µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:15.898478Z","caller":"traceutil/trace.go:171","msg":"trace[1336159358] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"432.308647ms","start":"2026-07-30T21:03:15.466114Z","end":"2026-07-30T21:03:15.898423Z","steps":["trace[1336159358] 'process raft request' (duration: 432.00005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:15.898795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:15.466075Z","time spent":"432.585224ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:03:15.898887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.254684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"warn","ts":"2026-07-30T21:03:15.898914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.60454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-30T21:03:15.899024Z","caller":"traceutil/trace.go:171","msg":"trace[1322507874] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2275; }","duration":"319.733933ms","start":"2026-07-30T21:03:15.579265Z","end":"2026-07-30T21:03:15.898999Z","steps":["trace[1322507874] 'agreement among raft nodes before linearized reading' (duration: 319.432515ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:15.899033Z","caller":"traceutil/trace.go:171","msg":"trace[178432510] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2275; }","duration":"382.503971ms","start":"2026-07-30T21:03:15.516475Z","end":"2026-07-30T21:03:15.898979Z","steps":["trace[178432510] 'agreement among raft nodes before linearized reading' (duration: 382.034198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:15.899153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:15.516442Z","time spent":"382.696206ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T21:03:15.89908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:15.579194Z","time spent":"319.875677ms","remote":"127.0.0.1:40780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-30T21:04:18.892073Z","caller":"traceutil/trace.go:171","msg":"trace[85462585] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"130.673549ms","start":"2026-07-30T21:04:18.761353Z","end":"2026-07-30T21:04:18.892026Z","steps":["trace[85462585] 'process raft request' (duration: 130.376112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:20.412849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767087894420623,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:04:20.594805Z","caller":"traceutil/trace.go:171","msg":"trace[2009684964] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"783.765133ms","start":"2026-07-30T21:04:19.810999Z","end":"2026-07-30T21:04:20.594764Z","steps":["trace[2009684964] 'process raft request' (duration: 783.480726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:20.595019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:19.810949Z","time spent":"783.971448ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:04:20.603469Z","caller":"traceutil/trace.go:171","msg":"trace[1349783521] linearizableReadLoop","detail":"{readStateIndex:2785; appliedIndex:2783; }","duration":"691.629181ms","start":"2026-07-30T21:04:19.911803Z","end":"2026-07-30T21:04:20.603432Z","steps":["trace[1349783521] 'read index received' (duration: 682.672731ms)","trace[1349783521] 'applied index is now lower than readState.Index' (duration: 8.95468ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:04:20.603667Z","caller":"traceutil/trace.go:171","msg":"trace[1366078455] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"364.323775ms","start":"2026-07-30T21:04:20.239325Z","end":"2026-07-30T21:04:20.603649Z","steps":["trace[1366078455] 'process raft request' (duration: 363.936186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:20.603773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.353191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-30T21:04:20.603824Z","caller":"traceutil/trace.go:171","msg":"trace[904775905] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2693; }","duration":"418.456703ms","start":"2026-07-30T21:04:20.185356Z","end":"2026-07-30T21:04:20.603813Z","steps":["trace[904775905] 'agreement among raft nodes before linearized reading' (duration: 418.30377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:20.603819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:20.239306Z","time spent":"364.403317ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:20.603855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:20.185334Z","time spent":"418.514015ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T21:04:20.603994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.198324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:04:20.604024Z","caller":"traceutil/trace.go:171","msg":"trace[1696199644] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2693; }","duration":"692.273236ms","start":"2026-07-30T21:04:19.911744Z","end":"2026-07-30T21:04:20.604017Z","steps":["trace[1696199644] 'agreement among raft nodes before linearized reading' (duration: 692.221045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:20.604044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:19.911715Z","time spent":"692.323418ms","remote":"127.0.0.1:59414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:04:20.604318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.366597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-07-30T21:04:20.60445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.499797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-30T21:04:20.604478Z","caller":"traceutil/trace.go:171","msg":"trace[869467716] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2693; }","duration":"417.598543ms","start":"2026-07-30T21:04:20.186849Z","end":"2026-07-30T21:04:20.604447Z","steps":["trace[869467716] 'agreement among raft nodes before linearized reading' (duration: 417.197333ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:20.604516Z","caller":"traceutil/trace.go:171","msg":"trace[1289408798] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2693; }","duration":"412.583609ms","start":"2026-07-30T21:04:20.191918Z","end":"2026-07-30T21:04:20.604501Z","steps":["trace[1289408798] 'agreement among raft nodes before linearized reading' (duration: 412.172209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:20.604542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:20.186817Z","time spent":"417.712856ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T21:04:20.60456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:20.191909Z","time spent":"412.64336ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T21:04:38.950637Z","caller":"traceutil/trace.go:171","msg":"trace[2052265437] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"284.372252ms","start":"2026-07-30T21:04:38.666219Z","end":"2026-07-30T21:04:38.950592Z","steps":["trace[2052265437] 'process raft request' (duration: 283.959392ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:55.184874Z","caller":"traceutil/trace.go:171","msg":"trace[811149235] transaction","detail":"{read_only:false; response_revision:2892; number_of_response:1; }","duration":"221.220499ms","start":"2026-07-30T21:04:54.963615Z","end":"2026-07-30T21:04:55.184835Z","steps":["trace[811149235] 'process raft request' (duration: 221.037955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.030972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.898388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:04:56.03151Z","caller":"traceutil/trace.go:171","msg":"trace[489110584] linearizableReadLoop","detail":"{readStateIndex:2994; appliedIndex:2992; }","duration":"635.749551ms","start":"2026-07-30T21:04:55.395739Z","end":"2026-07-30T21:04:56.031488Z","steps":["trace[489110584] 'read index received' (duration: 460.116444ms)","trace[489110584] 'applied index is now lower than readState.Index' (duration: 175.631527ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:04:56.031543Z","caller":"traceutil/trace.go:171","msg":"trace[2043928094] transaction","detail":"{read_only:false; response_revision:2893; number_of_response:1; }","duration":"1.020226723s","start":"2026-07-30T21:04:55.011257Z","end":"2026-07-30T21:04:56.031484Z","steps":["trace[2043928094] 'process raft request' (duration: 844.716549ms)","trace[2043928094] 'compare' (duration: 174.777475ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:04:56.031757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.011216Z","time spent":"1.020426798s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:04:56.031622Z","caller":"traceutil/trace.go:171","msg":"trace[892281325] transaction","detail":"{read_only:false; response_revision:2894; number_of_response:1; }","duration":"977.354726ms","start":"2026-07-30T21:04:55.054191Z","end":"2026-07-30T21:04:56.031545Z","steps":["trace[892281325] 'process raft request' (duration: 977.222692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.031793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.047938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-30T21:04:56.031774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.229945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:04:56.031903Z","caller":"traceutil/trace.go:171","msg":"trace[1958119766] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2894; }","duration":"636.220783ms","start":"2026-07-30T21:04:55.395655Z","end":"2026-07-30T21:04:56.031876Z","steps":["trace[1958119766] 'agreement among raft nodes before linearized reading' (duration: 635.927336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.031962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.395631Z","time spent":"636.319355ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:04:56.031955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.306655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-30T21:04:56.032023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.476679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-30T21:04:56.031981Z","caller":"traceutil/trace.go:171","msg":"trace[684690908] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2894; }","duration":"429.430829ms","start":"2026-07-30T21:04:55.602495Z","end":"2026-07-30T21:04:56.031926Z","steps":["trace[684690908] 'agreement among raft nodes before linearized reading' (duration: 429.216324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.032142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.226126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-07-30T21:04:56.032156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.350924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-30T21:04:56.032156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.022516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T21:04:56.03218Z","caller":"traceutil/trace.go:171","msg":"trace[1965106895] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2894; }","duration":"339.295108ms","start":"2026-07-30T21:04:55.692873Z","end":"2026-07-30T21:04:56.032168Z","steps":["trace[1965106895] 'agreement among raft nodes before linearized reading' (duration: 339.210146ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:56.032187Z","caller":"traceutil/trace.go:171","msg":"trace[326918909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2894; }","duration":"502.398396ms","start":"2026-07-30T21:04:55.52978Z","end":"2026-07-30T21:04:56.032178Z","steps":["trace[326918909] 'agreement among raft nodes before linearized reading' (duration: 502.317744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.032179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.470688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T21:04:56.032139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.602479Z","time spent":"429.642785ms","remote":"127.0.0.1:40710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:04:56.032221Z","caller":"traceutil/trace.go:171","msg":"trace[975699318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2894; }","duration":"502.566351ms","start":"2026-07-30T21:04:55.529644Z","end":"2026-07-30T21:04:56.03221Z","steps":["trace[975699318] 'agreement among raft nodes before linearized reading' (duration: 502.462308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.032267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.423525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:04:56.032063Z","caller":"traceutil/trace.go:171","msg":"trace[1279953556] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2894; }","duration":"349.564591ms","start":"2026-07-30T21:04:55.682486Z","end":"2026-07-30T21:04:56.032051Z","steps":["trace[1279953556] 'agreement among raft nodes before linearized reading' (duration: 349.411007ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:56.032299Z","caller":"traceutil/trace.go:171","msg":"trace[2092010457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2894; }","duration":"424.478697ms","start":"2026-07-30T21:04:55.607813Z","end":"2026-07-30T21:04:56.032291Z","steps":["trace[2092010457] 'agreement among raft nodes before linearized reading' (duration: 424.431706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.032311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.682455Z","time spent":"349.847808ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T21:04:56.032332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.6078Z","time spent":"424.526568ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:04:56.032269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.52961Z","time spent":"502.652523ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T21:04:56.032311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.906989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-30T21:04:56.032403Z","caller":"traceutil/trace.go:171","msg":"trace[1269644695] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2894; }","duration":"350.026012ms","start":"2026-07-30T21:04:55.682362Z","end":"2026-07-30T21:04:56.032388Z","steps":["trace[1269644695] 'agreement among raft nodes before linearized reading' (duration: 349.880379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.032438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.682341Z","time spent":"350.088404ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T21:04:56.032206Z","caller":"traceutil/trace.go:171","msg":"trace[482413003] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2894; }","duration":"502.085558ms","start":"2026-07-30T21:04:55.530103Z","end":"2026-07-30T21:04:56.032189Z","steps":["trace[482413003] 'agreement among raft nodes before linearized reading' (duration: 501.912654ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:56.032066Z","caller":"traceutil/trace.go:171","msg":"trace[1141783714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2894; }","duration":"502.477109ms","start":"2026-07-30T21:04:55.529562Z","end":"2026-07-30T21:04:56.032039Z","steps":["trace[1141783714] 'agreement among raft nodes before linearized reading' (duration: 502.159771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.032524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.054162Z","time spent":"977.708715ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:56.032559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.530092Z","time spent":"502.455387ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T21:04:56.031955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.039137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-30T21:04:56.032609Z","caller":"traceutil/trace.go:171","msg":"trace[47213260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2894; }","duration":"502.746534ms","start":"2026-07-30T21:04:55.52985Z","end":"2026-07-30T21:04:56.032597Z","steps":["trace[47213260] 'agreement among raft nodes before linearized reading' (duration: 501.891323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.032229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.529773Z","time spent":"502.447108ms","remote":"127.0.0.1:54708","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-07-30T21:04:56.032657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.529837Z","time spent":"502.807665ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T21:04:56.032571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.529532Z","time spent":"503.027862ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T21:04:56.032213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:55.692856Z","time spent":"339.35033ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T21:04:56.405005Z","caller":"traceutil/trace.go:171","msg":"trace[1741587586] transaction","detail":"{read_only:false; response_revision:2895; number_of_response:1; }","duration":"363.966996ms","start":"2026-07-30T21:04:56.040993Z","end":"2026-07-30T21:04:56.40496Z","steps":["trace[1741587586] 'process raft request' (duration: 358.689916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.405212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:56.04098Z","time spent":"364.13242ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:04:56.406936Z","caller":"traceutil/trace.go:171","msg":"trace[169727071] transaction","detail":"{read_only:false; response_revision:2896; number_of_response:1; }","duration":"365.583417ms","start":"2026-07-30T21:04:56.041337Z","end":"2026-07-30T21:04:56.406921Z","steps":["trace[169727071] 'process raft request' (duration: 365.467404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.407047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:56.041329Z","time spent":"365.655318ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:04:56.407258Z","caller":"traceutil/trace.go:171","msg":"trace[1837364036] transaction","detail":"{read_only:false; response_revision:2897; number_of_response:1; }","duration":"365.400602ms","start":"2026-07-30T21:04:56.041843Z","end":"2026-07-30T21:04:56.407244Z","steps":["trace[1837364036] 'process raft request' (duration: 365.043073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.40734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:56.041837Z","time spent":"365.457364ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:04:56.407396Z","caller":"traceutil/trace.go:171","msg":"trace[1515458848] transaction","detail":"{read_only:false; response_revision:2899; number_of_response:1; }","duration":"365.409752ms","start":"2026-07-30T21:04:56.041971Z","end":"2026-07-30T21:04:56.407381Z","steps":["trace[1515458848] 'process raft request' (duration: 365.243208ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:56.407448Z","caller":"traceutil/trace.go:171","msg":"trace[2004787809] linearizableReadLoop","detail":"{readStateIndex:3003; appliedIndex:2994; }","duration":"338.497249ms","start":"2026-07-30T21:04:56.068921Z","end":"2026-07-30T21:04:56.407418Z","steps":["trace[2004787809] 'read index received' (duration: 330.781768ms)","trace[2004787809] 'applied index is now lower than readState.Index' (duration: 7.714151ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:04:56.407628Z","caller":"traceutil/trace.go:171","msg":"trace[102331509] transaction","detail":"{read_only:false; response_revision:2900; number_of_response:1; }","duration":"365.529706ms","start":"2026-07-30T21:04:56.042084Z","end":"2026-07-30T21:04:56.407614Z","steps":["trace[102331509] 'process raft request' (duration: 365.168537ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:56.40771Z","caller":"traceutil/trace.go:171","msg":"trace[909979724] transaction","detail":"{read_only:false; response_revision:2898; number_of_response:1; }","duration":"365.775171ms","start":"2026-07-30T21:04:56.041922Z","end":"2026-07-30T21:04:56.407697Z","steps":["trace[909979724] 'process raft request' (duration: 365.163776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.40777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:56.042073Z","time spent":"365.591957ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:56.40778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.815467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:04:56.407825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:56.041894Z","time spent":"365.862983ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:04:56.407899Z","caller":"traceutil/trace.go:171","msg":"trace[561974276] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2903; }","duration":"338.980521ms","start":"2026-07-30T21:04:56.068892Z","end":"2026-07-30T21:04:56.407872Z","steps":["trace[561974276] 'agreement among raft nodes before linearized reading' (duration: 338.782876ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:56.407964Z","caller":"traceutil/trace.go:171","msg":"trace[642884065] transaction","detail":"{read_only:false; response_revision:2902; number_of_response:1; }","duration":"364.141591ms","start":"2026-07-30T21:04:56.04381Z","end":"2026-07-30T21:04:56.407951Z","steps":["trace[642884065] 'process raft request' (duration: 363.517856ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:56.408013Z","caller":"traceutil/trace.go:171","msg":"trace[1835779362] transaction","detail":"{read_only:false; response_revision:2903; number_of_response:1; }","duration":"357.541068ms","start":"2026-07-30T21:04:56.050458Z","end":"2026-07-30T21:04:56.407999Z","steps":["trace[1835779362] 'process raft request' (duration: 356.916393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.408038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:56.043802Z","time spent":"364.200332ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:56.408047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.214001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-30T21:04:56.408098Z","caller":"traceutil/trace.go:171","msg":"trace[991571675] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2903; }","duration":"273.297833ms","start":"2026-07-30T21:04:56.134775Z","end":"2026-07-30T21:04:56.408073Z","steps":["trace[991571675] 'agreement among raft nodes before linearized reading' (duration: 273.16234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.408109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:56.050443Z","time spent":"357.59773ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:04:56.40817Z","caller":"traceutil/trace.go:171","msg":"trace[1072119318] transaction","detail":"{read_only:false; response_revision:2901; number_of_response:1; }","duration":"365.273178ms","start":"2026-07-30T21:04:56.042884Z","end":"2026-07-30T21:04:56.408157Z","steps":["trace[1072119318] 'process raft request' (duration: 364.408017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:56.408235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:56.042878Z","time spent":"365.325939ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:56.407489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:56.041944Z","time spent":"365.497594ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:56.407961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:56.06888Z","time spent":"339.065793ms","remote":"127.0.0.1:59380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:09:24.287433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2724} {"level":"info","ts":"2026-07-30T21:09:24.363608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2724,"took":"75.010698ms","hash":2922526910,"current-db-size-bytes":32088064,"current-db-size":"32 MB","current-db-size-in-use-bytes":8781824,"current-db-size-in-use":"8.8 MB"} {"level":"info","ts":"2026-07-30T21:09:24.363714Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2922526910,"revision":2724,"compact-revision":-1} {"level":"info","ts":"2026-07-30T21:14:24.29434Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4349} {"level":"info","ts":"2026-07-30T21:14:24.334295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4349,"took":"38.413917ms","hash":3248595409,"current-db-size-bytes":32088064,"current-db-size":"32 MB","current-db-size-in-use-bytes":8314880,"current-db-size-in-use":"8.3 MB"} {"level":"info","ts":"2026-07-30T21:14:24.334386Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3248595409,"revision":4349,"compact-revision":2724} {"level":"info","ts":"2026-07-30T21:15:33.734247Z","caller":"traceutil/trace.go:171","msg":"trace[1493081024] transaction","detail":"{read_only:false; response_revision:7012; number_of_response:1; }","duration":"110.376649ms","start":"2026-07-30T21:15:33.623843Z","end":"2026-07-30T21:15:33.73422Z","steps":["trace[1493081024] 'process raft request' (duration: 94.5866ms)","trace[1493081024] 'compare' (duration: 15.669065ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:15:33.789675Z","caller":"traceutil/trace.go:171","msg":"trace[2014831593] linearizableReadLoop","detail":"{readStateIndex:7272; appliedIndex:7270; }","duration":"159.200532ms","start":"2026-07-30T21:15:33.630451Z","end":"2026-07-30T21:15:33.789651Z","steps":["trace[2014831593] 'read index received' (duration: 87.998898ms)","trace[2014831593] 'applied index is now lower than readState.Index' (duration: 71.200544ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:15:33.789747Z","caller":"traceutil/trace.go:171","msg":"trace[1470944290] transaction","detail":"{read_only:false; response_revision:7013; number_of_response:1; }","duration":"161.064496ms","start":"2026-07-30T21:15:33.628648Z","end":"2026-07-30T21:15:33.789713Z","steps":["trace[1470944290] 'process raft request' (duration: 160.862041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:15:33.789881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.404857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-07-30T21:15:33.78993Z","caller":"traceutil/trace.go:171","msg":"trace[300313986] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:7013; }","duration":"159.479608ms","start":"2026-07-30T21:15:33.630434Z","end":"2026-07-30T21:15:33.789914Z","steps":["trace[300313986] 'agreement among raft nodes before linearized reading' (duration: 159.314354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:15:42.219381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.350398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T21:15:42.219478Z","caller":"traceutil/trace.go:171","msg":"trace[1268196738] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:7105; }","duration":"164.497011ms","start":"2026-07-30T21:15:42.054961Z","end":"2026-07-30T21:15:42.219458Z","steps":["trace[1268196738] 'range keys from in-memory index tree' (duration: 164.231364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:15:42.219472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.401643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T21:15:42.219536Z","caller":"traceutil/trace.go:171","msg":"trace[1599794823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7105; }","duration":"182.517096ms","start":"2026-07-30T21:15:42.037003Z","end":"2026-07-30T21:15:42.21952Z","steps":["trace[1599794823] 'range keys from in-memory index tree' (duration: 182.222659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:15:42.219514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.031545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-30T21:15:42.21957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.905914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T21:15:42.219616Z","caller":"traceutil/trace.go:171","msg":"trace[675756266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7105; }","duration":"284.17587ms","start":"2026-07-30T21:15:41.935415Z","end":"2026-07-30T21:15:42.219591Z","steps":["trace[675756266] 'range keys from in-memory index tree' (duration: 283.878812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:15:42.219424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.304853ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:15:42.219629Z","caller":"traceutil/trace.go:171","msg":"trace[1013871516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7105; }","duration":"171.990127ms","start":"2026-07-30T21:15:42.047622Z","end":"2026-07-30T21:15:42.219613Z","steps":["trace[1013871516] 'range keys from in-memory index tree' (duration: 171.772701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:15:42.219382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.087354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:15:42.219817Z","caller":"traceutil/trace.go:171","msg":"trace[1037993975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7105; }","duration":"171.565357ms","start":"2026-07-30T21:15:42.048242Z","end":"2026-07-30T21:15:42.219807Z","steps":["trace[1037993975] 'range keys from in-memory index tree' (duration: 170.867368ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:42.219795Z","caller":"traceutil/trace.go:171","msg":"trace[1228890567] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7105; }","duration":"226.676663ms","start":"2026-07-30T21:15:41.993102Z","end":"2026-07-30T21:15:42.219778Z","steps":["trace[1228890567] 'range keys from in-memory index tree' (duration: 226.293763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:15:42.220206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.268161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-30T21:15:42.220274Z","caller":"traceutil/trace.go:171","msg":"trace[1022757909] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7105; }","duration":"286.378094ms","start":"2026-07-30T21:15:41.933878Z","end":"2026-07-30T21:15:42.220256Z","steps":["trace[1022757909] 'range keys from in-memory index tree' (duration: 285.003119ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:42.357357Z","caller":"traceutil/trace.go:171","msg":"trace[748934264] transaction","detail":"{read_only:false; response_revision:7107; number_of_response:1; }","duration":"133.192041ms","start":"2026-07-30T21:15:42.224136Z","end":"2026-07-30T21:15:42.357328Z","steps":["trace[748934264] 'process raft request' (duration: 132.990656ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:42.357694Z","caller":"traceutil/trace.go:171","msg":"trace[1462176364] transaction","detail":"{read_only:false; response_revision:7110; number_of_response:1; }","duration":"131.407757ms","start":"2026-07-30T21:15:42.226265Z","end":"2026-07-30T21:15:42.357673Z","steps":["trace[1462176364] 'process raft request' (duration: 131.344956ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:42.357852Z","caller":"traceutil/trace.go:171","msg":"trace[1674045197] transaction","detail":"{read_only:false; response_revision:7108; number_of_response:1; }","duration":"133.500359ms","start":"2026-07-30T21:15:42.224334Z","end":"2026-07-30T21:15:42.357834Z","steps":["trace[1674045197] 'process raft request' (duration: 132.942756ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:42.357988Z","caller":"traceutil/trace.go:171","msg":"trace[1961019504] transaction","detail":"{read_only:false; response_revision:7109; number_of_response:1; }","duration":"133.11111ms","start":"2026-07-30T21:15:42.224869Z","end":"2026-07-30T21:15:42.35798Z","steps":["trace[1961019504] 'process raft request' (duration: 132.660139ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:42.357963Z","caller":"traceutil/trace.go:171","msg":"trace[48613868] transaction","detail":"{read_only:false; response_revision:7111; number_of_response:1; }","duration":"130.947936ms","start":"2026-07-30T21:15:42.226992Z","end":"2026-07-30T21:15:42.35794Z","steps":["trace[48613868] 'process raft request' (duration: 130.655729ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:42.761397Z","caller":"traceutil/trace.go:171","msg":"trace[680847744] linearizableReadLoop","detail":"{readStateIndex:7383; appliedIndex:7378; }","duration":"101.000787ms","start":"2026-07-30T21:15:42.660373Z","end":"2026-07-30T21:15:42.761374Z","steps":["trace[680847744] 'read index received' (duration: 13.153524ms)","trace[680847744] 'applied index is now lower than readState.Index' (duration: 87.846013ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:15:42.76173Z","caller":"traceutil/trace.go:171","msg":"trace[12005442] transaction","detail":"{read_only:false; response_revision:7119; number_of_response:1; }","duration":"117.125644ms","start":"2026-07-30T21:15:42.644589Z","end":"2026-07-30T21:15:42.761715Z","steps":["trace[12005442] 'process raft request' (duration: 116.585551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:15:42.762057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.130686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13220"} {"level":"info","ts":"2026-07-30T21:15:42.762107Z","caller":"traceutil/trace.go:171","msg":"trace[1187948446] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:7121; }","duration":"152.214299ms","start":"2026-07-30T21:15:42.609879Z","end":"2026-07-30T21:15:42.762093Z","steps":["trace[1187948446] 'agreement among raft nodes before linearized reading' (duration: 152.045935ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:42.762478Z","caller":"traceutil/trace.go:171","msg":"trace[1343304037] transaction","detail":"{read_only:false; response_revision:7120; number_of_response:1; }","duration":"112.935432ms","start":"2026-07-30T21:15:42.649529Z","end":"2026-07-30T21:15:42.762465Z","steps":["trace[1343304037] 'process raft request' (duration: 111.757053ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:42.762697Z","caller":"traceutil/trace.go:171","msg":"trace[2047804111] transaction","detail":"{read_only:false; response_revision:7121; number_of_response:1; }","duration":"100.568417ms","start":"2026-07-30T21:15:42.662113Z","end":"2026-07-30T21:15:42.762681Z","steps":["trace[2047804111] 'process raft request' (duration: 99.215304ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:43.000584Z","caller":"traceutil/trace.go:171","msg":"trace[15502037] linearizableReadLoop","detail":"{readStateIndex:7389; appliedIndex:7386; }","duration":"114.528871ms","start":"2026-07-30T21:15:42.886033Z","end":"2026-07-30T21:15:43.000562Z","steps":["trace[15502037] 'read index received' (duration: 10.307644ms)","trace[15502037] 'applied index is now lower than readState.Index' (duration: 104.220157ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:15:43.001095Z","caller":"traceutil/trace.go:171","msg":"trace[1918301124] transaction","detail":"{read_only:false; response_revision:7124; number_of_response:1; }","duration":"132.974795ms","start":"2026-07-30T21:15:42.868102Z","end":"2026-07-30T21:15:43.001076Z","steps":["trace[1918301124] 'process raft request' (duration: 132.195086ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:43.001367Z","caller":"traceutil/trace.go:171","msg":"trace[1204080555] transaction","detail":"{read_only:false; response_revision:7125; number_of_response:1; }","duration":"133.124379ms","start":"2026-07-30T21:15:42.868228Z","end":"2026-07-30T21:15:43.001352Z","steps":["trace[1204080555] 'process raft request' (duration: 132.234497ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:43.001588Z","caller":"traceutil/trace.go:171","msg":"trace[2082633590] transaction","detail":"{read_only:false; response_revision:7126; number_of_response:1; }","duration":"132.893993ms","start":"2026-07-30T21:15:42.868668Z","end":"2026-07-30T21:15:43.001562Z","steps":["trace[2082633590] 'process raft request' (duration: 131.852187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:15:43.002016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.965277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-30T21:15:43.002064Z","caller":"traceutil/trace.go:171","msg":"trace[1324434218] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:7126; }","duration":"116.054759ms","start":"2026-07-30T21:15:42.885996Z","end":"2026-07-30T21:15:43.00205Z","steps":["trace[1324434218] 'agreement among raft nodes before linearized reading' (duration: 115.888205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:15:43.002089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.588433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:475"} {"level":"info","ts":"2026-07-30T21:15:43.002146Z","caller":"traceutil/trace.go:171","msg":"trace[343766162] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:7126; }","duration":"114.663224ms","start":"2026-07-30T21:15:42.887466Z","end":"2026-07-30T21:15:43.00213Z","steps":["trace[343766162] 'agreement among raft nodes before linearized reading' (duration: 114.50177ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:19:24.30088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6062} {"level":"info","ts":"2026-07-30T21:19:24.339934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6062,"took":"37.830309ms","hash":1832312827,"current-db-size-bytes":32088064,"current-db-size":"32 MB","current-db-size-in-use-bytes":25436160,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-30T21:19:24.340008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1832312827,"revision":6062,"compact-revision":4349} {"level":"info","ts":"2026-07-30T21:23:16.459477Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T21:23:16.470307Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-30T21:23:16.470477Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}