{"level":"warn","ts":"2026-04-17T18:45:01.06068Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T18:45:01.060774Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.44:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.44:2380","--initial-cluster=instance=https://199.19.213.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.44:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-17T18:45:01.060841Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T18:45:01.060854Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.44:2380"]} {"level":"info","ts":"2026-04-17T18:45:01.06087Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T18:45:01.061504Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"]} {"level":"info","ts":"2026-04-17T18:45:01.061574Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.44:2380"],"listen-peer-urls":["https://199.19.213.44:2380"],"advertise-client-urls":["https://199.19.213.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.44:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-17T18:45:01.065486Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.71929ms"} {"level":"info","ts":"2026-04-17T18:45:01.076692Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4f2979fc0f638003","cluster-id":"ce06e7bc3b23612b"} {"level":"info","ts":"2026-04-17T18:45:01.076795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T18:45:01.07682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became follower at term 0"} {"level":"info","ts":"2026-04-17T18:45:01.076835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4f2979fc0f638003 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T18:45:01.076844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became follower at term 1"} {"level":"info","ts":"2026-04-17T18:45:01.076887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=(5704224526538342403)"} {"level":"warn","ts":"2026-04-17T18:45:01.093576Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T18:45:01.095694Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T18:45:01.09736Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-17T18:45:01.100925Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4f2979fc0f638003","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T18:45:01.10111Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4f2979fc0f638003","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T18:45:01.101285Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T18:45:01.101327Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T18:45:01.101338Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T18:45:01.102063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=(5704224526538342403)"} {"level":"info","ts":"2026-04-17T18:45:01.102195Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ce06e7bc3b23612b","local-member-id":"4f2979fc0f638003","added-peer-id":"4f2979fc0f638003","added-peer-peer-urls":["https://199.19.213.44:2380"]} {"level":"info","ts":"2026-04-17T18:45:01.104242Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T18:45:01.104343Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.44:2380"} {"level":"info","ts":"2026-04-17T18:45:01.104375Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.44:2380"} {"level":"info","ts":"2026-04-17T18:45:01.10482Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T18:45:01.104769Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4f2979fc0f638003","initial-advertise-peer-urls":["https://199.19.213.44:2380"],"listen-peer-urls":["https://199.19.213.44:2380"],"advertise-client-urls":["https://199.19.213.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T18:45:01.977995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T18:45:01.978066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T18:45:01.978106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 received MsgPreVoteResp from 4f2979fc0f638003 at term 1"} {"level":"info","ts":"2026-04-17T18:45:01.978125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became candidate at term 2"} {"level":"info","ts":"2026-04-17T18:45:01.978142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 received MsgVoteResp from 4f2979fc0f638003 at term 2"} {"level":"info","ts":"2026-04-17T18:45:01.978158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became leader at term 2"} {"level":"info","ts":"2026-04-17T18:45:01.978177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4f2979fc0f638003 elected leader 4f2979fc0f638003 at term 2"} {"level":"info","ts":"2026-04-17T18:45:01.97913Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:45:01.980058Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4f2979fc0f638003","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.44:2379]}","request-path":"/0/members/4f2979fc0f638003/attributes","cluster-id":"ce06e7bc3b23612b","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T18:45:01.980214Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:45:01.980247Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:45:01.980631Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ce06e7bc3b23612b","local-member-id":"4f2979fc0f638003","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:45:01.980759Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:45:01.980791Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:45:01.981623Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T18:45:01.981713Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T18:45:01.981948Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T18:45:01.990678Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.44:2379"} {"level":"info","ts":"2026-04-17T18:45:03.6622Z","caller":"traceutil/trace.go:171","msg":"trace[501852516] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"447.894646ms","start":"2026-04-17T18:45:03.214276Z","end":"2026-04-17T18:45:03.66217Z","steps":["trace[501852516] 'process raft request' (duration: 447.626751ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:03.662549Z","caller":"traceutil/trace.go:171","msg":"trace[1227355988] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:5; }","duration":"447.866216ms","start":"2026-04-17T18:45:03.214658Z","end":"2026-04-17T18:45:03.662524Z","steps":["trace[1227355988] 'read index received' (duration: 447.858586ms)","trace[1227355988] 'applied index is now lower than readState.Index' (duration: 6.28µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:45:03.662877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.175591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:45:03.663006Z","caller":"traceutil/trace.go:171","msg":"trace[1521515987] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:2; }","duration":"448.340995ms","start":"2026-04-17T18:45:03.214642Z","end":"2026-04-17T18:45:03.662983Z","steps":["trace[1521515987] 'agreement among raft nodes before linearized reading' (duration: 448.037729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:03.663006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.214265Z","time spent":"448.043079ms","remote":"127.0.0.1:41542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:45:03.663071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.214636Z","time spent":"448.425266ms","remote":"127.0.0.1:41544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-17T18:45:04.163465Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389758392472704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:45:04.664353Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389758392472704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:45:05.164917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389758392472704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:45:05.665474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389758392472704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:45:05.712209Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.420724418s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T18:45:05.712803Z","caller":"traceutil/trace.go:171","msg":"trace[450242654] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"2.42427842s","start":"2026-04-17T18:45:03.288487Z","end":"2026-04-17T18:45:05.712765Z","steps":["trace[450242654] 'process raft request' (duration: 2.424067916s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:05.712894Z","caller":"traceutil/trace.go:171","msg":"trace[678271299] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"2.413414204s","start":"2026-04-17T18:45:03.299456Z","end":"2026-04-17T18:45:05.71287Z","steps":["trace[678271299] 'process raft request' (duration: 2.413380934s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:05.712984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.28847Z","time spent":"2.424409242s","remote":"127.0.0.1:42022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:45:05.71309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.299449Z","time spent":"2.413502256s","remote":"127.0.0.1:41662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:45:05.71337Z","caller":"traceutil/trace.go:171","msg":"trace[655830020] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"2.424494072s","start":"2026-04-17T18:45:03.288854Z","end":"2026-04-17T18:45:05.713348Z","steps":["trace[655830020] 'process raft request' (duration: 2.423859001s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:05.713452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.288845Z","time spent":"2.424573174s","remote":"127.0.0.1:42022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:45:05.713479Z","caller":"traceutil/trace.go:171","msg":"trace[1905387128] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"2.424567094s","start":"2026-04-17T18:45:03.288843Z","end":"2026-04-17T18:45:05.71341Z","steps":["trace[1905387128] 'process raft request' (duration: 2.423889552s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:05.713605Z","caller":"traceutil/trace.go:171","msg":"trace[769593611] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"2.424715116s","start":"2026-04-17T18:45:03.288861Z","end":"2026-04-17T18:45:05.713576Z","steps":["trace[769593611] 'process raft request' (duration: 2.423891921s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:05.713591Z","caller":"traceutil/trace.go:171","msg":"trace[859555756] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"2.424800618s","start":"2026-04-17T18:45:03.288777Z","end":"2026-04-17T18:45:05.713578Z","steps":["trace[859555756] 'process raft request' (duration: 2.423907662s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:05.713672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.288816Z","time spent":"2.424769887s","remote":"127.0.0.1:42022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:45:05.713694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.288767Z","time spent":"2.42489922s","remote":"127.0.0.1:42022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:45:05.713719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.288853Z","time spent":"2.424812558s","remote":"127.0.0.1:42022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:45:05.713815Z","caller":"traceutil/trace.go:171","msg":"trace[1646591901] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"2.424971562s","start":"2026-04-17T18:45:03.288837Z","end":"2026-04-17T18:45:05.713808Z","steps":["trace[1646591901] 'process raft request' (duration: 2.423940833s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:05.713849Z","caller":"traceutil/trace.go:171","msg":"trace[1435219799] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"2.418219686s","start":"2026-04-17T18:45:03.295619Z","end":"2026-04-17T18:45:05.713839Z","steps":["trace[1435219799] 'process raft request' (duration: 2.417188746s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:05.713865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.28879Z","time spent":"2.425046294s","remote":"127.0.0.1:41936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:45:05.713901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.295608Z","time spent":"2.418273437s","remote":"127.0.0.1:41734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:45:05.755562Z","caller":"traceutil/trace.go:171","msg":"trace[1139218139] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"2.091439427s","start":"2026-04-17T18:45:03.664086Z","end":"2026-04-17T18:45:05.755526Z","steps":["trace[1139218139] 'process raft request' (duration: 2.077351032s)","trace[1139218139] 'compare' (duration: 13.912772ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:45:05.755887Z","caller":"traceutil/trace.go:171","msg":"trace[928294773] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"2.09313434s","start":"2026-04-17T18:45:03.662718Z","end":"2026-04-17T18:45:05.755852Z","steps":["trace[928294773] 'read index received' (duration: 2.049884246s)","trace[928294773] 'applied index is now lower than readState.Index' (duration: 43.248814ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:45:05.755923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.664063Z","time spent":"2.091579879s","remote":"127.0.0.1:41544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:45:05.756235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.530036629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:45:05.756334Z","caller":"traceutil/trace.go:171","msg":"trace[894700004] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:18; }","duration":"2.530191662s","start":"2026-04-17T18:45:03.226118Z","end":"2026-04-17T18:45:05.75631Z","steps":["trace[894700004] 'agreement among raft nodes before linearized reading' (duration: 2.529979158s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:05.756393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.226104Z","time spent":"2.530265213s","remote":"127.0.0.1:41886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-17T18:45:05.756416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.467588073s","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:4"} {"level":"info","ts":"2026-04-17T18:45:05.756491Z","caller":"traceutil/trace.go:171","msg":"trace[1939460553] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:18; }","duration":"2.467687026s","start":"2026-04-17T18:45:03.288785Z","end":"2026-04-17T18:45:05.756472Z","steps":["trace[1939460553] 'agreement among raft nodes before linearized reading' (duration: 2.467580654s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:05.756576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494300504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:45:05.756625Z","caller":"traceutil/trace.go:171","msg":"trace[741788294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"1.494343794s","start":"2026-04-17T18:45:04.262267Z","end":"2026-04-17T18:45:05.756611Z","steps":["trace[741788294] 'agreement among raft nodes before linearized reading' (duration: 1.494289573s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:05.756627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.469567641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:45:05.756664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:04.262214Z","time spent":"1.494443006s","remote":"127.0.0.1:41494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T18:45:05.756692Z","caller":"traceutil/trace.go:171","msg":"trace[2063104405] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"2.469665534s","start":"2026-04-17T18:45:03.287012Z","end":"2026-04-17T18:45:05.756677Z","steps":["trace[2063104405] 'agreement among raft nodes before linearized reading' (duration: 2.469576332s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:05.756735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.286999Z","time spent":"2.469725414s","remote":"127.0.0.1:41586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-17T18:45:05.756753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.416878491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:45:05.756803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.569938507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:45:05.756811Z","caller":"traceutil/trace.go:171","msg":"trace[1087700480] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"2.416934682s","start":"2026-04-17T18:45:03.33986Z","end":"2026-04-17T18:45:05.756795Z","steps":["trace[1087700480] 'agreement among raft nodes before linearized reading' (duration: 2.41685325s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:05.756841Z","caller":"traceutil/trace.go:171","msg":"trace[788346263] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:18; }","duration":"1.569997297s","start":"2026-04-17T18:45:04.186834Z","end":"2026-04-17T18:45:05.756831Z","steps":["trace[788346263] 'agreement among raft nodes before linearized reading' (duration: 1.569945266s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:05.756859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.339841Z","time spent":"2.417005663s","remote":"127.0.0.1:41586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-17T18:45:05.756627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.288778Z","time spent":"2.467832578s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-17T18:45:05.756873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:04.186822Z","time spent":"1.570044928s","remote":"127.0.0.1:41828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-17T18:45:05.757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.471175731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.44\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:45:05.757051Z","caller":"traceutil/trace.go:171","msg":"trace[1493842088] range","detail":"{range_begin:/registry/masterleases/199.19.213.44; range_end:; response_count:0; response_revision:18; }","duration":"2.471256993s","start":"2026-04-17T18:45:03.285778Z","end":"2026-04-17T18:45:05.757035Z","steps":["trace[1493842088] 'agreement among raft nodes before linearized reading' (duration: 2.471182482s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:05.757159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.285728Z","time spent":"2.471418657s","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.44\" "} {"level":"warn","ts":"2026-04-17T18:45:05.756263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.569521039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T18:45:05.757271Z","caller":"traceutil/trace.go:171","msg":"trace[1310347986] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:18; }","duration":"1.57064354s","start":"2026-04-17T18:45:04.186611Z","end":"2026-04-17T18:45:05.757254Z","steps":["trace[1310347986] 'agreement among raft nodes before linearized reading' (duration: 1.569524349s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:05.757326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.490931843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T18:45:05.757338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:04.186589Z","time spent":"1.570733692s","remote":"127.0.0.1:41852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-04-17T18:45:05.757361Z","caller":"traceutil/trace.go:171","msg":"trace[2074033429] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"2.490967694s","start":"2026-04-17T18:45:03.266384Z","end":"2026-04-17T18:45:05.757351Z","steps":["trace[2074033429] 'agreement among raft nodes before linearized reading' (duration: 2.490916403s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:05.757417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:03.266363Z","time spent":"2.491042565s","remote":"127.0.0.1:41586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-17T18:45:14.787124Z","caller":"traceutil/trace.go:171","msg":"trace[1528484309] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:321; }","duration":"112.366634ms","start":"2026-04-17T18:45:14.67473Z","end":"2026-04-17T18:45:14.787096Z","steps":["trace[1528484309] 'read index received' (duration: 112.12857ms)","trace[1528484309] 'applied index is now lower than readState.Index' (duration: 236.734µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:45:14.787286Z","caller":"traceutil/trace.go:171","msg":"trace[304400805] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"149.002826ms","start":"2026-04-17T18:45:14.638222Z","end":"2026-04-17T18:45:14.787225Z","steps":["trace[304400805] 'process raft request' (duration: 148.696211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:14.787365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.608998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-17T18:45:14.787659Z","caller":"traceutil/trace.go:171","msg":"trace[55226982] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:310; }","duration":"112.903594ms","start":"2026-04-17T18:45:14.674695Z","end":"2026-04-17T18:45:14.787599Z","steps":["trace[55226982] 'agreement among raft nodes before linearized reading' (duration: 112.576078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.412863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389758392473609,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T18:45:33.627899Z","caller":"traceutil/trace.go:171","msg":"trace[1460304689] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"891.092903ms","start":"2026-04-17T18:45:32.736787Z","end":"2026-04-17T18:45:33.62788Z","steps":["trace[1460304689] 'process raft request' (duration: 829.168436ms)","trace[1460304689] 'compare' (duration: 61.856866ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:45:33.628015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.736751Z","time spent":"891.218166ms","remote":"127.0.0.1:42022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:45:33.651262Z","caller":"traceutil/trace.go:171","msg":"trace[893332294] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"911.20238ms","start":"2026-04-17T18:45:32.738536Z","end":"2026-04-17T18:45:33.649738Z","steps":["trace[893332294] 'process raft request' (duration: 911.031506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.651505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.738521Z","time spent":"912.886741ms","remote":"127.0.0.1:42022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:45:33.652221Z","caller":"traceutil/trace.go:171","msg":"trace[469369937] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"911.297062ms","start":"2026-04-17T18:45:32.740887Z","end":"2026-04-17T18:45:33.652184Z","steps":["trace[469369937] 'process raft request' (duration: 908.839504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.652366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.740882Z","time spent":"911.395013ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:45:33.654628Z","caller":"traceutil/trace.go:171","msg":"trace[501975954] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"909.526326ms","start":"2026-04-17T18:45:32.74507Z","end":"2026-04-17T18:45:33.654596Z","steps":["trace[501975954] 'process raft request' (duration: 905.200303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.65478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.74506Z","time spent":"909.65874ms","remote":"127.0.0.1:42022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:45:33.654844Z","caller":"traceutil/trace.go:171","msg":"trace[1757472663] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"914.050124ms","start":"2026-04-17T18:45:32.740746Z","end":"2026-04-17T18:45:33.654796Z","steps":["trace[1757472663] 'process raft request' (duration: 908.909975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.655012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.740734Z","time spent":"914.183956ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:45:33.655769Z","caller":"traceutil/trace.go:171","msg":"trace[775647407] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"906.172913ms","start":"2026-04-17T18:45:32.749477Z","end":"2026-04-17T18:45:33.65565Z","steps":["trace[775647407] 'process raft request' (duration: 903.40019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.655852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.74946Z","time spent":"906.349016ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:45:33.655868Z","caller":"traceutil/trace.go:171","msg":"trace[1778818726] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"913.34494ms","start":"2026-04-17T18:45:32.742494Z","end":"2026-04-17T18:45:33.655839Z","steps":["trace[1778818726] 'process raft request' (duration: 907.544759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.655964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.742487Z","time spent":"913.419601ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:45:33.656332Z","caller":"traceutil/trace.go:171","msg":"trace[1725981762] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"911.70178ms","start":"2026-04-17T18:45:32.744591Z","end":"2026-04-17T18:45:33.656293Z","steps":["trace[1725981762] 'process raft request' (duration: 905.578331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.656389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.744568Z","time spent":"911.798222ms","remote":"127.0.0.1:42022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:45:33.656446Z","caller":"traceutil/trace.go:171","msg":"trace[1053232724] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"904.302997ms","start":"2026-04-17T18:45:32.752105Z","end":"2026-04-17T18:45:33.656408Z","steps":["trace[1053232724] 'process raft request' (duration: 902.448601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.656645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.752091Z","time spent":"904.45283ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:45:33.656692Z","caller":"traceutil/trace.go:171","msg":"trace[1016498095] transaction","detail":"{read_only:false; number_of_response:1; response_revision:481; }","duration":"913.935212ms","start":"2026-04-17T18:45:32.742739Z","end":"2026-04-17T18:45:33.656674Z","steps":["trace[1016498095] 'process raft request' (duration: 907.359885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.656783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.742733Z","time spent":"913.994244ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7733,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:45:33.657364Z","caller":"traceutil/trace.go:171","msg":"trace[1081348348] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"904.876948ms","start":"2026-04-17T18:45:32.75247Z","end":"2026-04-17T18:45:33.657347Z","steps":["trace[1081348348] 'process raft request' (duration: 902.735208ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:33.657463Z","caller":"traceutil/trace.go:171","msg":"trace[2015083686] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"908.12934ms","start":"2026-04-17T18:45:32.749304Z","end":"2026-04-17T18:45:33.657434Z","steps":["trace[2015083686] 'process raft request' (duration: 901.048114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.657627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.752462Z","time spent":"905.118113ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:45:33.657724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.74929Z","time spent":"908.354305ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:45:33.658542Z","caller":"traceutil/trace.go:171","msg":"trace[1743241323] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"909.108319ms","start":"2026-04-17T18:45:32.749407Z","end":"2026-04-17T18:45:33.658516Z","steps":["trace[1743241323] 'process raft request' (duration: 902.172226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.659835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.7494Z","time spent":"909.19905ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:45:33.659843Z","caller":"traceutil/trace.go:171","msg":"trace[1838558860] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:495; }","duration":"747.000701ms","start":"2026-04-17T18:45:32.912796Z","end":"2026-04-17T18:45:33.659796Z","steps":["trace[1838558860] 'read index received' (duration: 653.258164ms)","trace[1838558860] 'applied index is now lower than readState.Index' (duration: 93.741817ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:45:33.660136Z","caller":"traceutil/trace.go:171","msg":"trace[844472135] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"891.194415ms","start":"2026-04-17T18:45:32.768927Z","end":"2026-04-17T18:45:33.660122Z","steps":["trace[844472135] 'process raft request' (duration: 889.385681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.659956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.155904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:45:33.660274Z","caller":"traceutil/trace.go:171","msg":"trace[1044989911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"747.49847ms","start":"2026-04-17T18:45:32.912763Z","end":"2026-04-17T18:45:33.660262Z","steps":["trace[1044989911] 'agreement among raft nodes before linearized reading' (duration: 747.102973ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:33.660258Z","caller":"traceutil/trace.go:171","msg":"trace[1352372530] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"778.81021ms","start":"2026-04-17T18:45:32.88142Z","end":"2026-04-17T18:45:33.66023Z","steps":["trace[1352372530] 'process raft request' (duration: 778.216079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:33.660251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.76888Z","time spent":"891.345878ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:45:33.660384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.881413Z","time spent":"778.932023ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:45:33.660399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:32.912758Z","time spent":"747.595642ms","remote":"127.0.0.1:41494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T18:45:36.228178Z","caller":"traceutil/trace.go:171","msg":"trace[72603049] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"139.889131ms","start":"2026-04-17T18:45:36.088265Z","end":"2026-04-17T18:45:36.228154Z","steps":["trace[72603049] 'read index received' (duration: 139.732938ms)","trace[72603049] 'applied index is now lower than readState.Index' (duration: 155.363µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:45:36.228254Z","caller":"traceutil/trace.go:171","msg":"trace[1445332083] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"220.554459ms","start":"2026-04-17T18:45:36.00766Z","end":"2026-04-17T18:45:36.228214Z","steps":["trace[1445332083] 'process raft request' (duration: 220.364525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:36.228395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.111746ms","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-04-17T18:45:36.228434Z","caller":"traceutil/trace.go:171","msg":"trace[901745235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"140.198167ms","start":"2026-04-17T18:45:36.088225Z","end":"2026-04-17T18:45:36.228423Z","steps":["trace[901745235] 'agreement among raft nodes before linearized reading' (duration: 140.046614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:36.228508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.138106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T18:45:36.228599Z","caller":"traceutil/trace.go:171","msg":"trace[540905537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:546; }","duration":"140.253299ms","start":"2026-04-17T18:45:36.088322Z","end":"2026-04-17T18:45:36.228575Z","steps":["trace[540905537] 'agreement among raft nodes before linearized reading' (duration: 139.993964ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:36.708687Z","caller":"traceutil/trace.go:171","msg":"trace[1461541602] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"295.001906ms","start":"2026-04-17T18:45:36.413663Z","end":"2026-04-17T18:45:36.708665Z","steps":["trace[1461541602] 'read index received' (duration: 231.499768ms)","trace[1461541602] 'applied index is now lower than readState.Index' (duration: 63.501278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:45:36.708827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.164179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:45:36.708855Z","caller":"traceutil/trace.go:171","msg":"trace[339398041] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:548; }","duration":"295.24355ms","start":"2026-04-17T18:45:36.413601Z","end":"2026-04-17T18:45:36.708845Z","steps":["trace[339398041] 'agreement among raft nodes before linearized reading' (duration: 295.185459ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:36.708852Z","caller":"traceutil/trace.go:171","msg":"trace[1261877054] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"475.21184ms","start":"2026-04-17T18:45:36.233584Z","end":"2026-04-17T18:45:36.708796Z","steps":["trace[1261877054] 'process raft request' (duration: 411.627641ms)","trace[1261877054] 'compare' (duration: 63.372876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:45:36.708903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.871329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.44\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-04-17T18:45:36.708955Z","caller":"traceutil/trace.go:171","msg":"trace[1475776335] range","detail":"{range_begin:/registry/masterleases/199.19.213.44; range_end:; response_count:1; response_revision:548; }","duration":"130.964581ms","start":"2026-04-17T18:45:36.577978Z","end":"2026-04-17T18:45:36.708942Z","steps":["trace[1475776335] 'agreement among raft nodes before linearized reading' (duration: 130.871549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:36.709027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:36.233565Z","time spent":"475.373634ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:45:36.709129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.282992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T18:45:36.709225Z","caller":"traceutil/trace.go:171","msg":"trace[301643642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"113.571247ms","start":"2026-04-17T18:45:36.595635Z","end":"2026-04-17T18:45:36.709206Z","steps":["trace[301643642] 'agreement among raft nodes before linearized reading' (duration: 113.281042ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:36.818309Z","caller":"traceutil/trace.go:171","msg":"trace[1054948970] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"104.121446ms","start":"2026-04-17T18:45:36.714169Z","end":"2026-04-17T18:45:36.818291Z","steps":["trace[1054948970] 'process raft request' (duration: 98.666851ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:40.805153Z","caller":"traceutil/trace.go:171","msg":"trace[1612078435] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"334.083624ms","start":"2026-04-17T18:45:40.471047Z","end":"2026-04-17T18:45:40.805131Z","steps":["trace[1612078435] 'process raft request' (duration: 333.947332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:40.805348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:40.471024Z","time spent":"334.263308ms","remote":"127.0.0.1:41666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:45:41.480748Z","caller":"traceutil/trace.go:171","msg":"trace[879762948] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"756.364551ms","start":"2026-04-17T18:45:40.724349Z","end":"2026-04-17T18:45:41.480713Z","steps":["trace[879762948] 'read index received' (duration: 80.960483ms)","trace[879762948] 'applied index is now lower than readState.Index' (duration: 675.402858ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:45:41.480839Z","caller":"traceutil/trace.go:171","msg":"trace[442330535] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"797.869537ms","start":"2026-04-17T18:45:40.682905Z","end":"2026-04-17T18:45:41.480775Z","steps":["trace[442330535] 'process raft request' (duration: 796.778306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:41.480982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:40.682878Z","time spent":"798.031059ms","remote":"127.0.0.1:41576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:45:41.481067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.040133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T18:45:41.481115Z","caller":"traceutil/trace.go:171","msg":"trace[691087137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"649.131834ms","start":"2026-04-17T18:45:40.83197Z","end":"2026-04-17T18:45:41.481101Z","steps":["trace[691087137] 'agreement among raft nodes before linearized reading' (duration: 648.951941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:41.481103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.728807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T18:45:41.481153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:40.831951Z","time spent":"649.193566ms","remote":"127.0.0.1:41748","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-04-17T18:45:41.481182Z","caller":"traceutil/trace.go:171","msg":"trace[1104022059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"756.824149ms","start":"2026-04-17T18:45:40.72434Z","end":"2026-04-17T18:45:41.481164Z","steps":["trace[1104022059] 'agreement among raft nodes before linearized reading' (duration: 756.572374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:41.481227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:40.7243Z","time spent":"756.918851ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:45:41.481326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.323926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:45:41.481359Z","caller":"traceutil/trace.go:171","msg":"trace[1077900008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"568.384538ms","start":"2026-04-17T18:45:40.912967Z","end":"2026-04-17T18:45:41.481351Z","steps":["trace[1077900008] 'agreement among raft nodes before linearized reading' (duration: 568.310976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:41.481382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:40.912952Z","time spent":"568.425338ms","remote":"127.0.0.1:41494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:45:41.759541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.158938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:45:41.759845Z","caller":"traceutil/trace.go:171","msg":"trace[471499077] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"269.607389ms","start":"2026-04-17T18:45:41.490186Z","end":"2026-04-17T18:45:41.759793Z","steps":["trace[471499077] 'process raft request' (duration: 269.450296ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:41.759858Z","caller":"traceutil/trace.go:171","msg":"trace[79545769] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"270.700699ms","start":"2026-04-17T18:45:41.489031Z","end":"2026-04-17T18:45:41.759731Z","steps":["trace[79545769] 'process raft request' (duration: 46.186215ms)","trace[79545769] 'compare' (duration: 224.066186ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:45:43.91613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000298928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T18:45:43.916263Z","caller":"traceutil/trace.go:171","msg":"trace[936532066] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000479672s","start":"2026-04-17T18:45:41.915756Z","end":"2026-04-17T18:45:43.916236Z","steps":["trace[936532066] 'agreement among raft nodes before linearized reading' (duration: 2.000320909s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:43.916315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:41.915738Z","time spent":"2.000565564s","remote":"127.0.0.1:41494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:45:43.972081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.062597493s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:45:43.972464Z","caller":"traceutil/trace.go:171","msg":"trace[1672001222] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"2.433810152s","start":"2026-04-17T18:45:41.538613Z","end":"2026-04-17T18:45:43.972424Z","steps":["trace[1672001222] 'process raft request' (duration: 370.779809ms)","trace[1672001222] 'compare' (duration: 2.062006003s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:45:43.972586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:41.538588Z","time spent":"2.433945804s","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:45:43.998568Z","caller":"traceutil/trace.go:171","msg":"trace[469064834] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"2.08275848s","start":"2026-04-17T18:45:41.915793Z","end":"2026-04-17T18:45:43.998552Z","steps":["trace[469064834] 'read index received' (duration: 27.231µs)","trace[469064834] 'applied index is now lower than readState.Index' (duration: 2.082729809s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:45:43.998726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.735139526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-17T18:45:43.998753Z","caller":"traceutil/trace.go:171","msg":"trace[1824241580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"1.735223697s","start":"2026-04-17T18:45:42.263523Z","end":"2026-04-17T18:45:43.998746Z","steps":["trace[1824241580] 'agreement among raft nodes before linearized reading' (duration: 1.735154746s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:43.998776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:42.263498Z","time spent":"1.735272208s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T18:45:43.998993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.700700905s","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-04-17T18:45:43.999022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.442376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T18:45:43.999079Z","caller":"traceutil/trace.go:171","msg":"trace[513038597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.700822018s","start":"2026-04-17T18:45:42.298234Z","end":"2026-04-17T18:45:43.999056Z","steps":["trace[513038597] 'agreement among raft nodes before linearized reading' (duration: 1.700624343s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:43.999127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:42.298223Z","time spent":"1.700895499s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T18:45:43.999122Z","caller":"traceutil/trace.go:171","msg":"trace[1658588825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"234.590058ms","start":"2026-04-17T18:45:43.764503Z","end":"2026-04-17T18:45:43.999093Z","steps":["trace[1658588825] 'agreement among raft nodes before linearized reading' (duration: 234.282671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:43.999321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.750902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T18:45:43.999365Z","caller":"traceutil/trace.go:171","msg":"trace[2141304183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"234.844103ms","start":"2026-04-17T18:45:43.764509Z","end":"2026-04-17T18:45:43.999353Z","steps":["trace[2141304183] 'agreement among raft nodes before linearized reading' (duration: 234.7078ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:46.125246Z","caller":"traceutil/trace.go:171","msg":"trace[1425228396] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"209.954425ms","start":"2026-04-17T18:45:45.915267Z","end":"2026-04-17T18:45:46.125222Z","steps":["trace[1425228396] 'read index received' (duration: 209.792702ms)","trace[1425228396] 'applied index is now lower than readState.Index' (duration: 160.453µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:45:46.125385Z","caller":"traceutil/trace.go:171","msg":"trace[1070178706] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"314.757484ms","start":"2026-04-17T18:45:45.810521Z","end":"2026-04-17T18:45:46.125279Z","steps":["trace[1070178706] 'process raft request' (duration: 314.5027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:46.125418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.138058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:45:46.125457Z","caller":"traceutil/trace.go:171","msg":"trace[1679218632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"210.24384ms","start":"2026-04-17T18:45:45.915203Z","end":"2026-04-17T18:45:46.125447Z","steps":["trace[1679218632] 'agreement among raft nodes before linearized reading' (duration: 210.146989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:46.125624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:45.810482Z","time spent":"314.956978ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:45:46.134907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.48079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T18:45:46.134944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.859547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T18:45:46.134996Z","caller":"traceutil/trace.go:171","msg":"trace[1906450529] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"125.651219ms","start":"2026-04-17T18:45:46.009338Z","end":"2026-04-17T18:45:46.134989Z","steps":["trace[1906450529] 'process raft request' (duration: 125.318523ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:46.135006Z","caller":"traceutil/trace.go:171","msg":"trace[1147679904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"120.945259ms","start":"2026-04-17T18:45:46.014046Z","end":"2026-04-17T18:45:46.134991Z","steps":["trace[1147679904] 'agreement among raft nodes before linearized reading' (duration: 120.767645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:46.135015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.472649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-17T18:45:46.134968Z","caller":"traceutil/trace.go:171","msg":"trace[1991310723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"120.596062ms","start":"2026-04-17T18:45:46.014359Z","end":"2026-04-17T18:45:46.134955Z","steps":["trace[1991310723] 'agreement among raft nodes before linearized reading' (duration: 120.418389ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:46.135064Z","caller":"traceutil/trace.go:171","msg":"trace[1198085448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"121.531891ms","start":"2026-04-17T18:45:46.013518Z","end":"2026-04-17T18:45:46.13505Z","steps":["trace[1198085448] 'agreement among raft nodes before linearized reading' (duration: 121.431939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:46.134907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.536086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T18:45:46.135223Z","caller":"traceutil/trace.go:171","msg":"trace[590917441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"126.878132ms","start":"2026-04-17T18:45:46.008289Z","end":"2026-04-17T18:45:46.135167Z","steps":["trace[590917441] 'agreement among raft nodes before linearized reading' (duration: 126.473074ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:56.479154Z","caller":"traceutil/trace.go:171","msg":"trace[2112281153] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"288.851857ms","start":"2026-04-17T18:45:56.190273Z","end":"2026-04-17T18:45:56.479125Z","steps":["trace[2112281153] 'process raft request' (duration: 288.678324ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:56.479209Z","caller":"traceutil/trace.go:171","msg":"trace[993237078] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:742; }","duration":"287.661624ms","start":"2026-04-17T18:45:56.191517Z","end":"2026-04-17T18:45:56.479179Z","steps":["trace[993237078] 'read index received' (duration: 287.649504ms)","trace[993237078] 'applied index is now lower than readState.Index' (duration: 10.13µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:45:56.479496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.95312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T18:45:56.479556Z","caller":"traceutil/trace.go:171","msg":"trace[516298312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:717; }","duration":"288.083913ms","start":"2026-04-17T18:45:56.19146Z","end":"2026-04-17T18:45:56.479544Z","steps":["trace[516298312] 'agreement among raft nodes before linearized reading' (duration: 287.851468ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:56.510687Z","caller":"traceutil/trace.go:171","msg":"trace[554529411] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"316.900176ms","start":"2026-04-17T18:45:56.193764Z","end":"2026-04-17T18:45:56.510664Z","steps":["trace[554529411] 'process raft request' (duration: 315.004699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:56.510854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:56.19375Z","time spent":"317.021798ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:45:56.510858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.428185ms","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-04-17T18:45:56.510972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.179971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2v8wk\" ","response":"range_response_count:1 size:18709"} {"level":"info","ts":"2026-04-17T18:45:56.511008Z","caller":"traceutil/trace.go:171","msg":"trace[1133885067] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2v8wk; range_end:; response_count:1; response_revision:719; }","duration":"137.239071ms","start":"2026-04-17T18:45:56.373756Z","end":"2026-04-17T18:45:56.510995Z","steps":["trace[1133885067] 'agreement among raft nodes before linearized reading' (duration: 137.133389ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:56.510989Z","caller":"traceutil/trace.go:171","msg":"trace[394711980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:719; }","duration":"318.587998ms","start":"2026-04-17T18:45:56.192385Z","end":"2026-04-17T18:45:56.510973Z","steps":["trace[394711980] 'agreement among raft nodes before linearized reading' (duration: 318.301763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:56.511068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:45:56.192367Z","time spent":"318.68348ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T18:45:59.669482Z","caller":"traceutil/trace.go:171","msg":"trace[1352331398] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"185.945145ms","start":"2026-04-17T18:45:59.48351Z","end":"2026-04-17T18:45:59.669455Z","steps":["trace[1352331398] 'process raft request' (duration: 185.784872ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:59.865237Z","caller":"traceutil/trace.go:171","msg":"trace[1129179035] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"277.303337ms","start":"2026-04-17T18:45:59.587899Z","end":"2026-04-17T18:45:59.865202Z","steps":["trace[1129179035] 'process raft request' (duration: 277.091303ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:59.873995Z","caller":"traceutil/trace.go:171","msg":"trace[1313051396] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"158.869447ms","start":"2026-04-17T18:45:59.715104Z","end":"2026-04-17T18:45:59.873974Z","steps":["trace[1313051396] 'process raft request' (duration: 158.778735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:35.207569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389758392474631,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:46:35.708562Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389758392474631,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:46:35.88986Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.227004789s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T18:46:35.890186Z","caller":"traceutil/trace.go:171","msg":"trace[246212484] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"1.227411088s","start":"2026-04-17T18:46:34.662752Z","end":"2026-04-17T18:46:35.890163Z","steps":["trace[246212484] 'process raft request' (duration: 1.227271546s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:35.890323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:34.662734Z","time spent":"1.22751914s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:46:35.910799Z","caller":"traceutil/trace.go:171","msg":"trace[1046217365] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:993; }","duration":"1.203469382s","start":"2026-04-17T18:46:34.707296Z","end":"2026-04-17T18:46:35.910765Z","steps":["trace[1046217365] 'read index received' (duration: 1.18275119s)","trace[1046217365] 'applied index is now lower than readState.Index' (duration: 20.717192ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:46:35.911021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203710137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T18:46:35.911038Z","caller":"traceutil/trace.go:171","msg":"trace[1343227416] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"1.073874857s","start":"2026-04-17T18:46:34.837134Z","end":"2026-04-17T18:46:35.911009Z","steps":["trace[1343227416] 'process raft request' (duration: 1.07351186s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:35.911203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:34.83711Z","time spent":"1.0740204s","remote":"127.0.0.1:41748","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-04-17T18:46:35.91107Z","caller":"traceutil/trace.go:171","msg":"trace[455065693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"1.203818339s","start":"2026-04-17T18:46:34.707238Z","end":"2026-04-17T18:46:35.911057Z","steps":["trace[455065693] 'agreement among raft nodes before linearized reading' (duration: 1.203656646s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:35.911306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:34.707215Z","time spent":"1.204082904s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T18:46:35.911022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198822049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T18:46:35.911379Z","caller":"traceutil/trace.go:171","msg":"trace[1520044100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"1.199241497s","start":"2026-04-17T18:46:34.71213Z","end":"2026-04-17T18:46:35.911372Z","steps":["trace[1520044100] 'agreement among raft nodes before linearized reading' (duration: 1.198774728s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:35.911398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:34.7121Z","time spent":"1.199292139s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:46:35.911454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197016354s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T18:46:35.911501Z","caller":"traceutil/trace.go:171","msg":"trace[349317666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:960; }","duration":"1.197092386s","start":"2026-04-17T18:46:34.714395Z","end":"2026-04-17T18:46:35.911487Z","steps":["trace[349317666] 'agreement among raft nodes before linearized reading' (duration: 1.196973993s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:35.911532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:34.71438Z","time spent":"1.197144466s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T18:46:36.648015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.060665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:46:36.648109Z","caller":"traceutil/trace.go:171","msg":"trace[50234957] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"732.533884ms","start":"2026-04-17T18:46:35.915558Z","end":"2026-04-17T18:46:36.648092Z","steps":["trace[50234957] 'process raft request' (duration: 610.317596ms)","trace[50234957] 'compare' (duration: 121.79736ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:46:36.648158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:35.915544Z","time spent":"732.590256ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:46:36.879501Z","caller":"traceutil/trace.go:171","msg":"trace[582995455] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"963.871772ms","start":"2026-04-17T18:46:35.915598Z","end":"2026-04-17T18:46:36.879469Z","steps":["trace[582995455] 'process raft request' (duration: 886.527375ms)","trace[582995455] 'compare' (duration: 77.148803ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:46:36.879544Z","caller":"traceutil/trace.go:171","msg":"trace[1404605266] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"963.859061ms","start":"2026-04-17T18:46:35.91567Z","end":"2026-04-17T18:46:36.879529Z","steps":["trace[1404605266] 'process raft request' (duration: 963.742149ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:36.879519Z","caller":"traceutil/trace.go:171","msg":"trace[1092609409] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:996; }","duration":"298.88823ms","start":"2026-04-17T18:46:36.58061Z","end":"2026-04-17T18:46:36.879498Z","steps":["trace[1092609409] 'read index received' (duration: 221.45051ms)","trace[1092609409] 'applied index is now lower than readState.Index' (duration: 77.43691ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:46:36.879656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:35.91558Z","time spent":"964.001024ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:46:36.879675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:35.915663Z","time spent":"963.937562ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:46:36.879731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.115094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.44\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-04-17T18:46:36.87974Z","caller":"traceutil/trace.go:171","msg":"trace[693360620] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"963.924593ms","start":"2026-04-17T18:46:35.915797Z","end":"2026-04-17T18:46:36.879721Z","steps":["trace[693360620] 'process raft request' (duration: 963.665538ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:36.879762Z","caller":"traceutil/trace.go:171","msg":"trace[214833822] range","detail":"{range_begin:/registry/masterleases/199.19.213.44; range_end:; response_count:1; response_revision:964; }","duration":"299.185145ms","start":"2026-04-17T18:46:36.580566Z","end":"2026-04-17T18:46:36.879751Z","steps":["trace[214833822] 'agreement among raft nodes before linearized reading' (duration: 299.043223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:36.87982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:35.915789Z","time spent":"963.989184ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:46:50.089696Z","caller":"traceutil/trace.go:171","msg":"trace[26456227] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"142.291268ms","start":"2026-04-17T18:46:49.947385Z","end":"2026-04-17T18:46:50.089677Z","steps":["trace[26456227] 'process raft request' (duration: 142.166895ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:50.095017Z","caller":"traceutil/trace.go:171","msg":"trace[1120003421] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"141.323327ms","start":"2026-04-17T18:46:49.953676Z","end":"2026-04-17T18:46:50.095Z","steps":["trace[1120003421] 'process raft request' (duration: 141.209635ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:51.086711Z","caller":"traceutil/trace.go:171","msg":"trace[739433027] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"128.773558ms","start":"2026-04-17T18:46:50.957903Z","end":"2026-04-17T18:46:51.086677Z","steps":["trace[739433027] 'process raft request' (duration: 101.792311ms)","trace[739433027] 'compare' (duration: 26.536478ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:46:51.086719Z","caller":"traceutil/trace.go:171","msg":"trace[1759046325] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"128.742138ms","start":"2026-04-17T18:46:50.957946Z","end":"2026-04-17T18:46:51.086688Z","steps":["trace[1759046325] 'process raft request' (duration: 128.594466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:58.620914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389758392474825,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T18:46:58.635123Z","caller":"traceutil/trace.go:171","msg":"trace[554910082] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"515.194997ms","start":"2026-04-17T18:46:58.119907Z","end":"2026-04-17T18:46:58.635102Z","steps":["trace[554910082] 'process raft request' (duration: 515.056994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:58.635299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:58.119882Z","time spent":"515.337769ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:46:59.6896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.542873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:46:59.689697Z","caller":"traceutil/trace.go:171","msg":"trace[82477493] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1071; }","duration":"1.569193698s","start":"2026-04-17T18:46:58.120487Z","end":"2026-04-17T18:46:59.689681Z","steps":["trace[82477493] 'read index received' (duration: 514.514763ms)","trace[82477493] 'applied index is now lower than readState.Index' (duration: 1.054677775s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:46:59.69002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.796342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-17T18:46:59.690001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383387577s","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":"warn","ts":"2026-04-17T18:46:59.690052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.569560626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T18:46:59.690066Z","caller":"traceutil/trace.go:171","msg":"trace[503292209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1033; }","duration":"565.879403ms","start":"2026-04-17T18:46:59.124174Z","end":"2026-04-17T18:46:59.690053Z","steps":["trace[503292209] 'agreement among raft nodes before linearized reading' (duration: 565.749381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:59.690054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.095241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T18:46:59.690081Z","caller":"traceutil/trace.go:171","msg":"trace[989204215] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1033; }","duration":"1.38352248s","start":"2026-04-17T18:46:58.306541Z","end":"2026-04-17T18:46:59.690064Z","steps":["trace[989204215] 'agreement among raft nodes before linearized reading' (duration: 1.383380337s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:59.690088Z","caller":"traceutil/trace.go:171","msg":"trace[1411264695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1033; }","duration":"1.569627997s","start":"2026-04-17T18:46:58.12045Z","end":"2026-04-17T18:46:59.690078Z","steps":["trace[1411264695] 'agreement among raft nodes before linearized reading' (duration: 1.569313401s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:59.690106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:59.124153Z","time spent":"565.947074ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:46:59.690086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32824953s","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":"info","ts":"2026-04-17T18:46:59.690107Z","caller":"traceutil/trace.go:171","msg":"trace[1109009926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1033; }","duration":"691.190262ms","start":"2026-04-17T18:46:58.998902Z","end":"2026-04-17T18:46:59.690092Z","steps":["trace[1109009926] 'agreement among raft nodes before linearized reading' (duration: 691.044089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:59.690128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:58.120433Z","time spent":"1.569687679s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:46:59.690128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:58.306523Z","time spent":"1.383591231s","remote":"127.0.0.1:41926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-17T18:46:59.690143Z","caller":"traceutil/trace.go:171","msg":"trace[1893793450] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1033; }","duration":"1.328372243s","start":"2026-04-17T18:46:58.361757Z","end":"2026-04-17T18:46:59.69013Z","steps":["trace[1893793450] 'agreement among raft nodes before linearized reading' (duration: 1.32823654s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:59.690148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:58.998881Z","time spent":"691.256314ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T18:46:59.690184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:58.361736Z","time spent":"1.328435084s","remote":"127.0.0.1:42914","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":"warn","ts":"2026-04-17T18:46:59.690277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.129049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:46:59.690299Z","caller":"traceutil/trace.go:171","msg":"trace[2059140888] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1033; }","duration":"343.21217ms","start":"2026-04-17T18:46:59.347079Z","end":"2026-04-17T18:46:59.690292Z","steps":["trace[2059140888] 'agreement among raft nodes before linearized reading' (duration: 343.173889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:59.690307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.685579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-17T18:46:59.690318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:59.347053Z","time spent":"343.258771ms","remote":"127.0.0.1:42912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-17T18:46:59.690333Z","caller":"traceutil/trace.go:171","msg":"trace[947627772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1033; }","duration":"565.743611ms","start":"2026-04-17T18:46:59.124582Z","end":"2026-04-17T18:46:59.690325Z","steps":["trace[947627772] 'agreement among raft nodes before linearized reading' (duration: 565.646199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:59.690375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:59.124564Z","time spent":"565.791341ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T18:46:59.690861Z","caller":"traceutil/trace.go:171","msg":"trace[1213957019] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"1.082775643s","start":"2026-04-17T18:46:58.60699Z","end":"2026-04-17T18:46:59.689765Z","steps":["trace[1213957019] 'process raft request' (duration: 106.990055ms)","trace[1213957019] 'compare' (duration: 975.416271ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:46:59.690978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:58.606957Z","time spent":"1.083952427s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:46:59.691614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.471518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:46:59.691711Z","caller":"traceutil/trace.go:171","msg":"trace[1028588139] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1033; }","duration":"929.115641ms","start":"2026-04-17T18:46:58.762573Z","end":"2026-04-17T18:46:59.691689Z","steps":["trace[1028588139] 'agreement among raft nodes before linearized reading' (duration: 927.355135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:59.691778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:58.762551Z","time spent":"929.207443ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T18:47:00.956004Z","caller":"traceutil/trace.go:171","msg":"trace[198893604] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"315.138162ms","start":"2026-04-17T18:47:00.640834Z","end":"2026-04-17T18:47:00.955972Z","steps":["trace[198893604] 'process raft request' (duration: 314.911347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:00.956173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:00.640808Z","time spent":"315.278285ms","remote":"127.0.0.1:41748","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-04-17T18:47:01.458209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389758392474842,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T18:47:01.486569Z","caller":"traceutil/trace.go:171","msg":"trace[1848560136] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"528.889368ms","start":"2026-04-17T18:47:00.957661Z","end":"2026-04-17T18:47:01.48655Z","steps":["trace[1848560136] 'read index received' (duration: 528.736236ms)","trace[1848560136] 'applied index is now lower than readState.Index' (duration: 152.462µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:47:01.486663Z","caller":"traceutil/trace.go:171","msg":"trace[1196305028] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"556.445106ms","start":"2026-04-17T18:47:00.93021Z","end":"2026-04-17T18:47:01.486656Z","steps":["trace[1196305028] 'process raft request' (duration: 556.237742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:01.486805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.141884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:47:01.486833Z","caller":"traceutil/trace.go:171","msg":"trace[1301572363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"529.202245ms","start":"2026-04-17T18:47:00.957623Z","end":"2026-04-17T18:47:01.486826Z","steps":["trace[1301572363] 'agreement among raft nodes before linearized reading' (duration: 529.150904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:01.486853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:00.957607Z","time spent":"529.242766ms","remote":"127.0.0.1:41480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:47:01.486845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.239412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:47:01.48693Z","caller":"traceutil/trace.go:171","msg":"trace[547963782] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1040; }","duration":"332.377674ms","start":"2026-04-17T18:47:01.154531Z","end":"2026-04-17T18:47:01.486909Z","steps":["trace[547963782] 'agreement among raft nodes before linearized reading' (duration: 332.236572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:01.486943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.099993ms","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":"info","ts":"2026-04-17T18:47:01.487006Z","caller":"traceutil/trace.go:171","msg":"trace[651877695] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1040; }","duration":"303.199415ms","start":"2026-04-17T18:47:01.183789Z","end":"2026-04-17T18:47:01.486988Z","steps":["trace[651877695] 'agreement among raft nodes before linearized reading' (duration: 303.100253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:01.487047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:00.930184Z","time spent":"556.498028ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:47:01.487068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:01.18377Z","time spent":"303.283686ms","remote":"127.0.0.1:42952","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":"warn","ts":"2026-04-17T18:47:01.486997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:01.154513Z","time spent":"332.463517ms","remote":"127.0.0.1:43094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:47:01.870223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.535007ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T18:47:03.331884Z","caller":"traceutil/trace.go:171","msg":"trace[332813985] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"370.372908ms","start":"2026-04-17T18:47:02.96149Z","end":"2026-04-17T18:47:03.331863Z","steps":["trace[332813985] 'process raft request' (duration: 370.243386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:03.332195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:02.961465Z","time spent":"370.484722ms","remote":"127.0.0.1:41748","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-04-17T18:47:14.13856Z","caller":"traceutil/trace.go:171","msg":"trace[1456257103] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1175; }","duration":"143.596444ms","start":"2026-04-17T18:47:13.994937Z","end":"2026-04-17T18:47:14.138534Z","steps":["trace[1456257103] 'read index received' (duration: 143.41582ms)","trace[1456257103] 'applied index is now lower than readState.Index' (duration: 178.474µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:47:14.138601Z","caller":"traceutil/trace.go:171","msg":"trace[650269337] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"194.691378ms","start":"2026-04-17T18:47:13.943866Z","end":"2026-04-17T18:47:14.138557Z","steps":["trace[650269337] 'process raft request' (duration: 194.500205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:14.138811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.844258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T18:47:14.13889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.471839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T18:47:14.138862Z","caller":"traceutil/trace.go:171","msg":"trace[1602915245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1131; }","duration":"143.96193ms","start":"2026-04-17T18:47:13.99489Z","end":"2026-04-17T18:47:14.138852Z","steps":["trace[1602915245] 'agreement among raft nodes before linearized reading' (duration: 143.754466ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:14.13892Z","caller":"traceutil/trace.go:171","msg":"trace[598774984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1131; }","duration":"143.509431ms","start":"2026-04-17T18:47:13.995399Z","end":"2026-04-17T18:47:14.138909Z","steps":["trace[598774984] 'agreement among raft nodes before linearized reading' (duration: 143.44129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:14.138822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.818907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T18:47:14.139023Z","caller":"traceutil/trace.go:171","msg":"trace[568056600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1131; }","duration":"144.035292ms","start":"2026-04-17T18:47:13.994981Z","end":"2026-04-17T18:47:14.139016Z","steps":["trace[568056600] 'agreement among raft nodes before linearized reading' (duration: 143.774167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:14.139043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.95879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:47:14.139069Z","caller":"traceutil/trace.go:171","msg":"trace[1068339215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1131; }","duration":"143.994321ms","start":"2026-04-17T18:47:13.995068Z","end":"2026-04-17T18:47:14.139062Z","steps":["trace[1068339215] 'agreement among raft nodes before linearized reading' (duration: 143.93258ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:15.706073Z","caller":"traceutil/trace.go:171","msg":"trace[7666892] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"333.831012ms","start":"2026-04-17T18:47:15.372221Z","end":"2026-04-17T18:47:15.706052Z","steps":["trace[7666892] 'process raft request' (duration: 333.71121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:15.706183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:15.372198Z","time spent":"333.929404ms","remote":"127.0.0.1:41748","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-04-17T18:47:16.350849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.267238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T18:47:16.350996Z","caller":"traceutil/trace.go:171","msg":"trace[204951645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1136; }","duration":"207.454222ms","start":"2026-04-17T18:47:16.143527Z","end":"2026-04-17T18:47:16.350981Z","steps":["trace[204951645] 'range keys from in-memory index tree' (duration: 207.025184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:16.35127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.852411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T18:47:16.351294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.484521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:47:16.351349Z","caller":"traceutil/trace.go:171","msg":"trace[283888500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1136; }","duration":"437.613794ms","start":"2026-04-17T18:47:15.913723Z","end":"2026-04-17T18:47:16.351337Z","steps":["trace[283888500] 'range keys from in-memory index tree' (duration: 437.371549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:16.351379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:15.913703Z","time spent":"437.670976ms","remote":"127.0.0.1:41494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T18:47:16.351414Z","caller":"traceutil/trace.go:171","msg":"trace[1671928155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1136; }","duration":"194.947343ms","start":"2026-04-17T18:47:16.156385Z","end":"2026-04-17T18:47:16.351332Z","steps":["trace[1671928155] 'range keys from in-memory index tree' (duration: 194.428843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:16.351723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.255678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T18:47:16.351757Z","caller":"traceutil/trace.go:171","msg":"trace[1585574122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1136; }","duration":"197.32975ms","start":"2026-04-17T18:47:16.154419Z","end":"2026-04-17T18:47:16.351749Z","steps":["trace[1585574122] 'range keys from in-memory index tree' (duration: 196.801599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:16.351837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.939673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T18:47:16.35188Z","caller":"traceutil/trace.go:171","msg":"trace[17609172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1136; }","duration":"195.982444ms","start":"2026-04-17T18:47:16.155892Z","end":"2026-04-17T18:47:16.351875Z","steps":["trace[17609172] 'range keys from in-memory index tree' (duration: 195.439943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:16.351947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.617396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:47:16.351961Z","caller":"traceutil/trace.go:171","msg":"trace[38617713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1136; }","duration":"195.668128ms","start":"2026-04-17T18:47:16.156288Z","end":"2026-04-17T18:47:16.351956Z","steps":["trace[38617713] 'range keys from in-memory index tree' (duration: 194.422523ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:32.438814Z","caller":"traceutil/trace.go:171","msg":"trace[1722419400] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"690.171303ms","start":"2026-04-17T18:47:31.748619Z","end":"2026-04-17T18:47:32.438791Z","steps":["trace[1722419400] 'process raft request' (duration: 689.522581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:32.439057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:31.748603Z","time spent":"690.378657ms","remote":"127.0.0.1:41748","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-04-17T18:47:33.167924Z","caller":"traceutil/trace.go:171","msg":"trace[1195782598] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1245; }","duration":"748.453031ms","start":"2026-04-17T18:47:32.419445Z","end":"2026-04-17T18:47:33.167898Z","steps":["trace[1195782598] 'read index received' (duration: 18.710022ms)","trace[1195782598] 'applied index is now lower than readState.Index' (duration: 729.741819ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:47:33.167995Z","caller":"traceutil/trace.go:171","msg":"trace[123347281] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"1.278385471s","start":"2026-04-17T18:47:31.889595Z","end":"2026-04-17T18:47:33.167981Z","steps":["trace[123347281] 'process raft request' (duration: 1.278147586s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:33.168081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:31.88957Z","time spent":"1.278459602s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:47:33.168132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.678946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T18:47:33.168168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.114658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:47:33.168163Z","caller":"traceutil/trace.go:171","msg":"trace[976344838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1199; }","duration":"748.740957ms","start":"2026-04-17T18:47:32.419413Z","end":"2026-04-17T18:47:33.168153Z","steps":["trace[976344838] 'agreement among raft nodes before linearized reading' (duration: 748.620315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:33.168166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.260723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:47:33.168183Z","caller":"traceutil/trace.go:171","msg":"trace[1914636110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1199; }","duration":"256.157109ms","start":"2026-04-17T18:47:32.912021Z","end":"2026-04-17T18:47:33.168178Z","steps":["trace[1914636110] 'agreement among raft nodes before linearized reading' (duration: 256.130769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:33.168192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:32.419397Z","time spent":"748.789258ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T18:47:33.168206Z","caller":"traceutil/trace.go:171","msg":"trace[1169260672] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1199; }","duration":"630.346604ms","start":"2026-04-17T18:47:32.537849Z","end":"2026-04-17T18:47:33.168196Z","steps":["trace[1169260672] 'agreement among raft nodes before linearized reading' (duration: 630.268732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:33.16824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:32.537827Z","time spent":"630.405116ms","remote":"127.0.0.1:43040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:47:33.168229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.104404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T18:47:33.168282Z","caller":"traceutil/trace.go:171","msg":"trace[340514348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1199; }","duration":"748.184655ms","start":"2026-04-17T18:47:32.420086Z","end":"2026-04-17T18:47:33.16827Z","steps":["trace[340514348] 'agreement among raft nodes before linearized reading' (duration: 748.013842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:33.168289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.775567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:47:33.168315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:32.42008Z","time spent":"748.223776ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T18:47:33.168321Z","caller":"traceutil/trace.go:171","msg":"trace[1501905269] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1199; }","duration":"520.839129ms","start":"2026-04-17T18:47:32.64747Z","end":"2026-04-17T18:47:33.168309Z","steps":["trace[1501905269] 'agreement among raft nodes before linearized reading' (duration: 520.798808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:33.168348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:32.647451Z","time spent":"520.88946ms","remote":"127.0.0.1:41700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:47:33.168416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.528784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:47:33.168431Z","caller":"traceutil/trace.go:171","msg":"trace[2022072110] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1199; }","duration":"734.563624ms","start":"2026-04-17T18:47:32.433863Z","end":"2026-04-17T18:47:33.168427Z","steps":["trace[2022072110] 'agreement among raft nodes before linearized reading' (duration: 734.517743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:33.168436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.296919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T18:47:33.168448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:32.433855Z","time spent":"734.588465ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T18:47:33.168461Z","caller":"traceutil/trace.go:171","msg":"trace[1787103627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1199; }","duration":"735.357241ms","start":"2026-04-17T18:47:32.433096Z","end":"2026-04-17T18:47:33.168453Z","steps":["trace[1787103627] 'agreement among raft nodes before linearized reading' (duration: 735.282779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:33.168482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:32.433077Z","time spent":"735.400101ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T18:47:33.168565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.006273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T18:47:33.168621Z","caller":"traceutil/trace.go:171","msg":"trace[1075869006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"739.088715ms","start":"2026-04-17T18:47:32.429519Z","end":"2026-04-17T18:47:33.168608Z","steps":["trace[1075869006] 'agreement among raft nodes before linearized reading' (duration: 738.944852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:33.168648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:32.42951Z","time spent":"739.133046ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T18:47:33.390692Z","caller":"traceutil/trace.go:171","msg":"trace[182355539] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"217.291977ms","start":"2026-04-17T18:47:33.173313Z","end":"2026-04-17T18:47:33.390605Z","steps":["trace[182355539] 'process raft request' (duration: 217.014572ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:33.391067Z","caller":"traceutil/trace.go:171","msg":"trace[2088740364] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"217.700785ms","start":"2026-04-17T18:47:33.173327Z","end":"2026-04-17T18:47:33.391028Z","steps":["trace[2088740364] 'process raft request' (duration: 217.224306ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:33.391148Z","caller":"traceutil/trace.go:171","msg":"trace[1320846930] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"217.726685ms","start":"2026-04-17T18:47:33.173391Z","end":"2026-04-17T18:47:33.391118Z","steps":["trace[1320846930] 'process raft request' (duration: 217.46699ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:33.391081Z","caller":"traceutil/trace.go:171","msg":"trace[676156842] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"215.699096ms","start":"2026-04-17T18:47:33.175346Z","end":"2026-04-17T18:47:33.391045Z","steps":["trace[676156842] 'process raft request' (duration: 215.650135ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:34.593962Z","caller":"traceutil/trace.go:171","msg":"trace[779033848] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"150.390068ms","start":"2026-04-17T18:47:34.44355Z","end":"2026-04-17T18:47:34.59394Z","steps":["trace[779033848] 'process raft request' (duration: 150.251605ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:35.533198Z","caller":"traceutil/trace.go:171","msg":"trace[1792908003] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1254; }","duration":"138.092104ms","start":"2026-04-17T18:47:35.395078Z","end":"2026-04-17T18:47:35.53317Z","steps":["trace[1792908003] 'read index received' (duration: 137.802238ms)","trace[1792908003] 'applied index is now lower than readState.Index' (duration: 288.466µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:47:35.53322Z","caller":"traceutil/trace.go:171","msg":"trace[541452509] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"336.537356ms","start":"2026-04-17T18:47:35.19666Z","end":"2026-04-17T18:47:35.533198Z","steps":["trace[541452509] 'process raft request' (duration: 336.322632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:35.533433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.338368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T18:47:35.533465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.758487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T18:47:35.533479Z","caller":"traceutil/trace.go:171","msg":"trace[1209828366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1206; }","duration":"138.43875ms","start":"2026-04-17T18:47:35.395028Z","end":"2026-04-17T18:47:35.533466Z","steps":["trace[1209828366] 'agreement among raft nodes before linearized reading' (duration: 138.284287ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:35.5335Z","caller":"traceutil/trace.go:171","msg":"trace[2080411915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1206; }","duration":"137.835898ms","start":"2026-04-17T18:47:35.395657Z","end":"2026-04-17T18:47:35.533493Z","steps":["trace[2080411915] 'agreement among raft nodes before linearized reading' (duration: 137.682015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:35.533513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.577833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T18:47:35.53357Z","caller":"traceutil/trace.go:171","msg":"trace[1827420029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1206; }","duration":"137.660325ms","start":"2026-04-17T18:47:35.395893Z","end":"2026-04-17T18:47:35.533553Z","steps":["trace[1827420029] 'agreement among raft nodes before linearized reading' (duration: 137.496912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:35.533582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:35.196642Z","time spent":"336.829311ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:47:35.533919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.931771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:47:35.533969Z","caller":"traceutil/trace.go:171","msg":"trace[1656625410] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1206; }","duration":"137.995813ms","start":"2026-04-17T18:47:35.395955Z","end":"2026-04-17T18:47:35.533951Z","steps":["trace[1656625410] 'agreement among raft nodes before linearized reading' (duration: 137.828319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:35.801616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.352551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:47:35.801733Z","caller":"traceutil/trace.go:171","msg":"trace[1652990872] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"256.036067ms","start":"2026-04-17T18:47:35.545672Z","end":"2026-04-17T18:47:35.801708Z","steps":["trace[1652990872] 'process raft request' (duration: 129.311569ms)","trace[1652990872] 'compare' (duration: 126.048644ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:47:35.841761Z","caller":"traceutil/trace.go:171","msg":"trace[718078208] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"295.302057ms","start":"2026-04-17T18:47:35.546436Z","end":"2026-04-17T18:47:35.841738Z","steps":["trace[718078208] 'process raft request' (duration: 295.256886ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:35.841772Z","caller":"traceutil/trace.go:171","msg":"trace[81517456] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"295.684826ms","start":"2026-04-17T18:47:35.546059Z","end":"2026-04-17T18:47:35.841744Z","steps":["trace[81517456] 'process raft request' (duration: 295.42823ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:35.841944Z","caller":"traceutil/trace.go:171","msg":"trace[2111011233] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"295.704516ms","start":"2026-04-17T18:47:35.54623Z","end":"2026-04-17T18:47:35.841935Z","steps":["trace[2111011233] 'process raft request' (duration: 295.380909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:36.092411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.871384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:47:36.092493Z","caller":"traceutil/trace.go:171","msg":"trace[717798315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"179.973036ms","start":"2026-04-17T18:47:35.912501Z","end":"2026-04-17T18:47:36.092474Z","steps":["trace[717798315] 'range keys from in-memory index tree' (duration: 179.772253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:37.083309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389758392475229,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T18:47:37.292734Z","caller":"traceutil/trace.go:171","msg":"trace[2051480864] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"755.292657ms","start":"2026-04-17T18:47:36.537411Z","end":"2026-04-17T18:47:37.292703Z","steps":["trace[2051480864] 'process raft request' (duration: 755.081823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:37.293147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:36.537392Z","time spent":"755.441801ms","remote":"127.0.0.1:41662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:47:37.719727Z","caller":"traceutil/trace.go:171","msg":"trace[1565289454] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1260; }","duration":"1.136502931s","start":"2026-04-17T18:47:36.583204Z","end":"2026-04-17T18:47:37.719707Z","steps":["trace[1565289454] 'read index received' (duration: 709.264753ms)","trace[1565289454] 'applied index is now lower than readState.Index' (duration: 427.237298ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:47:37.71982Z","caller":"traceutil/trace.go:171","msg":"trace[1645720443] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"1.120010734s","start":"2026-04-17T18:47:36.599777Z","end":"2026-04-17T18:47:37.719788Z","steps":["trace[1645720443] 'process raft request' (duration: 1.119876261s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:37.719932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136714925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.44\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-04-17T18:47:37.719971Z","caller":"traceutil/trace.go:171","msg":"trace[1860418985] range","detail":"{range_begin:/registry/masterleases/199.19.213.44; range_end:; response_count:1; response_revision:1214; }","duration":"1.136804167s","start":"2026-04-17T18:47:36.583159Z","end":"2026-04-17T18:47:37.719964Z","steps":["trace[1860418985] 'agreement among raft nodes before linearized reading' (duration: 1.136656794s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:37.719967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:36.599757Z","time spent":"1.120127596s","remote":"127.0.0.1:41748","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-04-17T18:47:37.719998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:36.583136Z","time spent":"1.136854948s","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.44\" "} {"level":"info","ts":"2026-04-17T18:47:37.720053Z","caller":"traceutil/trace.go:171","msg":"trace[1846763464] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"1.143426019s","start":"2026-04-17T18:47:36.5766Z","end":"2026-04-17T18:47:37.720026Z","steps":["trace[1846763464] 'process raft request' (duration: 1.142906909s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:37.720181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:36.57658Z","time spent":"1.143543281s","remote":"127.0.0.1:41550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":756,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:47:37.720223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.313011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:47:37.720304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.729132ms","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-04-17T18:47:37.720295Z","caller":"traceutil/trace.go:171","msg":"trace[1408853093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"806.405042ms","start":"2026-04-17T18:47:36.913854Z","end":"2026-04-17T18:47:37.720259Z","steps":["trace[1408853093] 'agreement among raft nodes before linearized reading' (duration: 806.3098ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:37.720328Z","caller":"traceutil/trace.go:171","msg":"trace[11831119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1214; }","duration":"173.787693ms","start":"2026-04-17T18:47:37.546534Z","end":"2026-04-17T18:47:37.720322Z","steps":["trace[11831119] 'agreement among raft nodes before linearized reading' (duration: 173.723662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:37.720356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:36.913822Z","time spent":"806.516604ms","remote":"127.0.0.1:41494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:47:37.720474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.130487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-17T18:47:37.720518Z","caller":"traceutil/trace.go:171","msg":"trace[499696218] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1214; }","duration":"423.205769ms","start":"2026-04-17T18:47:37.297302Z","end":"2026-04-17T18:47:37.720507Z","steps":["trace[499696218] 'agreement among raft nodes before linearized reading' (duration: 423.122737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:37.720546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:37.297285Z","time spent":"423.255629ms","remote":"127.0.0.1:41886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-17T18:47:38.39401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.627932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:47:38.394106Z","caller":"traceutil/trace.go:171","msg":"trace[1252557454] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"588.508393ms","start":"2026-04-17T18:47:37.805583Z","end":"2026-04-17T18:47:38.394091Z","steps":["trace[1252557454] 'read index received' (duration: 278.656907ms)","trace[1252557454] 'applied index is now lower than readState.Index' (duration: 309.850036ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:47:38.394189Z","caller":"traceutil/trace.go:171","msg":"trace[882474475] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"662.400313ms","start":"2026-04-17T18:47:37.731777Z","end":"2026-04-17T18:47:38.394178Z","steps":["trace[882474475] 'process raft request' (duration: 352.528445ms)","trace[882474475] 'compare' (duration: 309.232924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:47:38.394253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:37.73176Z","time spent":"662.461403ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":117,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:47:38.394543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.955352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T18:47:38.394582Z","caller":"traceutil/trace.go:171","msg":"trace[1805115970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"589.022303ms","start":"2026-04-17T18:47:37.80555Z","end":"2026-04-17T18:47:38.394573Z","steps":["trace[1805115970] 'agreement among raft nodes before linearized reading' (duration: 588.87411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:38.394612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:37.805535Z","time spent":"589.070715ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:47:38.394852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.495867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T18:47:38.394889Z","caller":"traceutil/trace.go:171","msg":"trace[1964651598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1218; }","duration":"549.560599ms","start":"2026-04-17T18:47:37.845319Z","end":"2026-04-17T18:47:38.39488Z","steps":["trace[1964651598] 'agreement among raft nodes before linearized reading' (duration: 549.472267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:38.394918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:37.845304Z","time spent":"549.60829ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T18:47:38.395152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.980218ms","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-04-17T18:47:38.395189Z","caller":"traceutil/trace.go:171","msg":"trace[1586867325] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1218; }","duration":"541.05195ms","start":"2026-04-17T18:47:37.854128Z","end":"2026-04-17T18:47:38.39518Z","steps":["trace[1586867325] 'agreement among raft nodes before linearized reading' (duration: 540.992119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:38.395222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:37.854112Z","time spent":"541.09861ms","remote":"127.0.0.1:41766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:47:38.395407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.317574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:47:38.395442Z","caller":"traceutil/trace.go:171","msg":"trace[1120551114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1218; }","duration":"549.363045ms","start":"2026-04-17T18:47:37.846071Z","end":"2026-04-17T18:47:38.395434Z","steps":["trace[1120551114] 'agreement among raft nodes before linearized reading' (duration: 549.276043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:38.39547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:37.846054Z","time spent":"549.409956ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T18:47:38.395637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.205622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T18:47:38.395672Z","caller":"traceutil/trace.go:171","msg":"trace[101302940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1218; }","duration":"550.252513ms","start":"2026-04-17T18:47:37.845411Z","end":"2026-04-17T18:47:38.395664Z","steps":["trace[101302940] 'agreement among raft nodes before linearized reading' (duration: 550.169672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:38.395699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:37.845408Z","time spent":"550.285154ms","remote":"127.0.0.1:41748","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-04-17T18:47:46.711852Z","caller":"traceutil/trace.go:171","msg":"trace[2138787118] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"428.868211ms","start":"2026-04-17T18:47:46.282961Z","end":"2026-04-17T18:47:46.711829Z","steps":["trace[2138787118] 'process raft request' (duration: 428.753769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:46.712172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:46.28294Z","time spent":"428.970593ms","remote":"127.0.0.1:41748","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-04-17T18:47:46.712211Z","caller":"traceutil/trace.go:171","msg":"trace[738091731] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"390.878787ms","start":"2026-04-17T18:47:46.321302Z","end":"2026-04-17T18:47:46.712181Z","steps":["trace[738091731] 'read index received' (duration: 390.344377ms)","trace[738091731] 'applied index is now lower than readState.Index' (duration: 532.01µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:47:46.712579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.255689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.44\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-04-17T18:47:46.71262Z","caller":"traceutil/trace.go:171","msg":"trace[1997511491] range","detail":"{range_begin:/registry/masterleases/199.19.213.44; range_end:; response_count:1; response_revision:1293; }","duration":"129.346841ms","start":"2026-04-17T18:47:46.583262Z","end":"2026-04-17T18:47:46.712609Z","steps":["trace[1997511491] 'agreement among raft nodes before linearized reading' (duration: 129.178098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:46.712594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.343752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T18:47:46.712739Z","caller":"traceutil/trace.go:171","msg":"trace[895535229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1293; }","duration":"190.511375ms","start":"2026-04-17T18:47:46.52221Z","end":"2026-04-17T18:47:46.712722Z","steps":["trace[895535229] 'agreement among raft nodes before linearized reading' (duration: 190.25327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:46.712888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.242896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T18:47:46.712922Z","caller":"traceutil/trace.go:171","msg":"trace[1565938377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"219.326698ms","start":"2026-04-17T18:47:46.493588Z","end":"2026-04-17T18:47:46.712915Z","steps":["trace[1565938377] 'agreement among raft nodes before linearized reading' (duration: 219.198085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:46.712771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.294591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T18:47:46.713146Z","caller":"traceutil/trace.go:171","msg":"trace[2005515920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1293; }","duration":"195.716599ms","start":"2026-04-17T18:47:46.517418Z","end":"2026-04-17T18:47:46.713135Z","steps":["trace[2005515920] 'agreement among raft nodes before linearized reading' (duration: 195.208049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:46.713493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.308511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T18:47:46.713535Z","caller":"traceutil/trace.go:171","msg":"trace[1330010824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"193.373453ms","start":"2026-04-17T18:47:46.52015Z","end":"2026-04-17T18:47:46.713523Z","steps":["trace[1330010824] 'agreement among raft nodes before linearized reading' (duration: 193.277081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:46.713702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.752835ms","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-04-17T18:47:46.713761Z","caller":"traceutil/trace.go:171","msg":"trace[1154648018] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1293; }","duration":"228.853118ms","start":"2026-04-17T18:47:46.484897Z","end":"2026-04-17T18:47:46.713751Z","steps":["trace[1154648018] 'agreement among raft nodes before linearized reading' (duration: 227.47054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:46.71367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.093062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:47:46.713848Z","caller":"traceutil/trace.go:171","msg":"trace[9238184] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1293; }","duration":"392.564911ms","start":"2026-04-17T18:47:46.321246Z","end":"2026-04-17T18:47:46.713811Z","steps":["trace[9238184] 'agreement among raft nodes before linearized reading' (duration: 391.097012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:46.71394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:46.321225Z","time spent":"392.685663ms","remote":"127.0.0.1:41964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:47:47.414256Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389758392475369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:47:47.914791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389758392475369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:47:48.207315Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.492202819s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T18:47:48.303922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:46.715001Z","time spent":"1.58891543s","remote":"127.0.0.1:41518","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T18:47:48.326596Z","caller":"traceutil/trace.go:171","msg":"trace[1048381722] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"1.609116151s","start":"2026-04-17T18:47:46.717448Z","end":"2026-04-17T18:47:48.326564Z","steps":["trace[1048381722] 'process raft request' (duration: 1.608898817s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:48.326607Z","caller":"traceutil/trace.go:171","msg":"trace[2033378842] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"1.608800036s","start":"2026-04-17T18:47:46.717784Z","end":"2026-04-17T18:47:48.326584Z","steps":["trace[2033378842] 'process raft request' (duration: 1.608711374s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:48.326819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:46.717421Z","time spent":"1.609277886s","remote":"127.0.0.1:41748","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-04-17T18:47:48.327011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:46.717762Z","time spent":"1.609074181s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:47:48.327343Z","caller":"traceutil/trace.go:171","msg":"trace[912893802] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"1.608971588s","start":"2026-04-17T18:47:46.718338Z","end":"2026-04-17T18:47:48.32731Z","steps":["trace[912893802] 'process raft request' (duration: 1.608473699s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:48.327384Z","caller":"traceutil/trace.go:171","msg":"trace[2047294064] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1344; }","duration":"1.413564936s","start":"2026-04-17T18:47:46.913789Z","end":"2026-04-17T18:47:48.327354Z","steps":["trace[2047294064] 'read index received' (duration: 1.293752775s)","trace[2047294064] 'applied index is now lower than readState.Index' (duration: 119.810861ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:47:48.327385Z","caller":"traceutil/trace.go:171","msg":"trace[2066858390] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"1.608313137s","start":"2026-04-17T18:47:46.719057Z","end":"2026-04-17T18:47:48.32737Z","steps":["trace[2066858390] 'process raft request' (duration: 1.608102623s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:48.327499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:46.718321Z","time spent":"1.609106872s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:47:48.32752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:46.719031Z","time spent":"1.608448149s","remote":"127.0.0.1:41748","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-04-17T18:47:48.32759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413774431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:47:48.327638Z","caller":"traceutil/trace.go:171","msg":"trace[22003690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"1.413887183s","start":"2026-04-17T18:47:46.913742Z","end":"2026-04-17T18:47:48.327629Z","steps":["trace[22003690] 'agreement among raft nodes before linearized reading' (duration: 1.41375621s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:48.327668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:46.91372Z","time spent":"1.413940694s","remote":"127.0.0.1:41494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T18:47:48.327751Z","caller":"traceutil/trace.go:171","msg":"trace[938320658] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"570.679198ms","start":"2026-04-17T18:47:47.756939Z","end":"2026-04-17T18:47:48.327618Z","steps":["trace[938320658] 'process raft request' (duration: 570.351212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:48.327889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:47.756872Z","time spent":"570.961374ms","remote":"127.0.0.1:41748","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-04-17T18:47:48.328427Z","caller":"traceutil/trace.go:171","msg":"trace[1124461340] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"1.60898394s","start":"2026-04-17T18:47:46.717899Z","end":"2026-04-17T18:47:48.326883Z","steps":["trace[1124461340] 'process raft request' (duration: 1.608646813s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:48.328544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:46.717886Z","time spent":"1.610602062s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:47:48.330847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.276124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:47:48.330892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.928984ms","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-04-17T18:47:48.330956Z","caller":"traceutil/trace.go:171","msg":"trace[1695213042] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1300; }","duration":"501.013785ms","start":"2026-04-17T18:47:47.829924Z","end":"2026-04-17T18:47:48.330937Z","steps":["trace[1695213042] 'agreement among raft nodes before linearized reading' (duration: 500.854731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:48.331002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:47.829901Z","time spent":"501.087517ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-17T18:47:48.331164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.840167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:47:48.331207Z","caller":"traceutil/trace.go:171","msg":"trace[945075804] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1300; }","duration":"764.920039ms","start":"2026-04-17T18:47:47.566277Z","end":"2026-04-17T18:47:48.331197Z","steps":["trace[945075804] 'agreement among raft nodes before linearized reading' (duration: 764.857158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:48.331241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:47.566257Z","time spent":"764.97502ms","remote":"127.0.0.1:41662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:47:48.330866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.365257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T18:47:48.331573Z","caller":"traceutil/trace.go:171","msg":"trace[1107511994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1300; }","duration":"532.105763ms","start":"2026-04-17T18:47:47.799457Z","end":"2026-04-17T18:47:48.331563Z","steps":["trace[1107511994] 'agreement among raft nodes before linearized reading' (duration: 531.265826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:48.331609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:47.799441Z","time spent":"532.157724ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T18:47:48.330962Z","caller":"traceutil/trace.go:171","msg":"trace[1799282902] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1300; }","duration":"999.368687ms","start":"2026-04-17T18:47:47.331552Z","end":"2026-04-17T18:47:48.330921Z","steps":["trace[1799282902] 'agreement among raft nodes before linearized reading' (duration: 999.183072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:48.331809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:47.331504Z","time spent":"1.000292494s","remote":"127.0.0.1:43014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:47:48.332081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.569084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:47:48.332935Z","caller":"traceutil/trace.go:171","msg":"trace[1451091647] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1300; }","duration":"441.711956ms","start":"2026-04-17T18:47:47.890451Z","end":"2026-04-17T18:47:48.332163Z","steps":["trace[1451091647] 'agreement among raft nodes before linearized reading' (duration: 440.252858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:47:48.333305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:47:47.890434Z","time spent":"442.855359ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-17T18:48:50.762325Z","caller":"traceutil/trace.go:171","msg":"trace[363348146] transaction","detail":"{read_only:false; response_revision:1585; number_of_response:1; }","duration":"109.895266ms","start":"2026-04-17T18:48:50.652397Z","end":"2026-04-17T18:48:50.762292Z","steps":["trace[363348146] 'process raft request' (duration: 109.720203ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:48:50.762607Z","caller":"traceutil/trace.go:171","msg":"trace[1076471527] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"109.122711ms","start":"2026-04-17T18:48:50.653464Z","end":"2026-04-17T18:48:50.762586Z","steps":["trace[1076471527] 'process raft request' (duration: 108.778785ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:48:50.762784Z","caller":"traceutil/trace.go:171","msg":"trace[1342572605] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"109.050209ms","start":"2026-04-17T18:48:50.653699Z","end":"2026-04-17T18:48:50.762749Z","steps":["trace[1342572605] 'process raft request' (duration: 108.730064ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:48:50.762871Z","caller":"traceutil/trace.go:171","msg":"trace[287271373] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"109.08693ms","start":"2026-04-17T18:48:50.653742Z","end":"2026-04-17T18:48:50.762829Z","steps":["trace[287271373] 'process raft request' (duration: 108.847145ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:48:52.036748Z","caller":"traceutil/trace.go:171","msg":"trace[1948470807] linearizableReadLoop","detail":"{readStateIndex:1655; appliedIndex:1654; }","duration":"124.917234ms","start":"2026-04-17T18:48:51.911788Z","end":"2026-04-17T18:48:52.036705Z","steps":["trace[1948470807] 'read index received' (duration: 29.667469ms)","trace[1948470807] 'applied index is now lower than readState.Index' (duration: 95.248135ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:48:52.036964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.134878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:48:52.03718Z","caller":"traceutil/trace.go:171","msg":"trace[1338995667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1590; }","duration":"125.418294ms","start":"2026-04-17T18:48:51.91173Z","end":"2026-04-17T18:48:52.037148Z","steps":["trace[1338995667] 'agreement among raft nodes before linearized reading' (duration: 125.107177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:02.034808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.519184ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T18:49:02.034961Z","caller":"traceutil/trace.go:171","msg":"trace[813630245] linearizableReadLoop","detail":"{readStateIndex:1702; appliedIndex:1701; }","duration":"122.66128ms","start":"2026-04-17T18:49:01.912273Z","end":"2026-04-17T18:49:02.034934Z","steps":["trace[813630245] 'read index received' (duration: 38.12µs)","trace[813630245] 'applied index is now lower than readState.Index' (duration: 122.61883ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:49:02.035066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.785233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:49:02.035104Z","caller":"traceutil/trace.go:171","msg":"trace[2099823746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1635; }","duration":"122.878174ms","start":"2026-04-17T18:49:01.912212Z","end":"2026-04-17T18:49:02.03509Z","steps":["trace[2099823746] 'agreement among raft nodes before linearized reading' (duration: 122.782692ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:49:03.09067Z","caller":"traceutil/trace.go:171","msg":"trace[80361041] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"270.157398ms","start":"2026-04-17T18:49:02.820417Z","end":"2026-04-17T18:49:03.090574Z","steps":["trace[80361041] 'process raft request' (duration: 269.73813ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:49:03.090763Z","caller":"traceutil/trace.go:171","msg":"trace[2042174312] linearizableReadLoop","detail":"{readStateIndex:1706; appliedIndex:1705; }","duration":"267.079438ms","start":"2026-04-17T18:49:02.823666Z","end":"2026-04-17T18:49:03.090746Z","steps":["trace[2042174312] 'read index received' (duration: 266.434126ms)","trace[2042174312] 'applied index is now lower than readState.Index' (duration: 644.112µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:49:03.091051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.320422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T18:49:03.091158Z","caller":"traceutil/trace.go:171","msg":"trace[1601002243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1639; }","duration":"267.498847ms","start":"2026-04-17T18:49:02.823638Z","end":"2026-04-17T18:49:03.091137Z","steps":["trace[1601002243] 'agreement among raft nodes before linearized reading' (duration: 267.225081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:03.091512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.763812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T18:49:03.091555Z","caller":"traceutil/trace.go:171","msg":"trace[299551943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1639; }","duration":"267.831173ms","start":"2026-04-17T18:49:02.823712Z","end":"2026-04-17T18:49:03.091544Z","steps":["trace[299551943] 'agreement among raft nodes before linearized reading' (duration: 267.69656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:03.091683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.594351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-17T18:49:03.091664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.623695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:49:03.091713Z","caller":"traceutil/trace.go:171","msg":"trace[856975311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1639; }","duration":"264.649253ms","start":"2026-04-17T18:49:02.827056Z","end":"2026-04-17T18:49:03.091705Z","steps":["trace[856975311] 'agreement among raft nodes before linearized reading' (duration: 264.567321ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:49:03.091749Z","caller":"traceutil/trace.go:171","msg":"trace[766572895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1639; }","duration":"177.790248ms","start":"2026-04-17T18:49:02.913939Z","end":"2026-04-17T18:49:03.091729Z","steps":["trace[766572895] 'agreement among raft nodes before linearized reading' (duration: 177.623445ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:49:03.263629Z","caller":"traceutil/trace.go:171","msg":"trace[273018168] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"167.741915ms","start":"2026-04-17T18:49:03.095849Z","end":"2026-04-17T18:49:03.263591Z","steps":["trace[273018168] 'process raft request' (duration: 126.103467ms)","trace[273018168] 'compare' (duration: 41.404284ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:49:03.264043Z","caller":"traceutil/trace.go:171","msg":"trace[1671085013] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"168.064681ms","start":"2026-04-17T18:49:03.095939Z","end":"2026-04-17T18:49:03.264003Z","steps":["trace[1671085013] 'process raft request' (duration: 167.603042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:37.063175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.371654ms","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-04-17T18:49:37.063264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.393777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:49:37.06329Z","caller":"traceutil/trace.go:171","msg":"trace[2032978296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1795; }","duration":"117.543777ms","start":"2026-04-17T18:49:36.945721Z","end":"2026-04-17T18:49:37.063265Z","steps":["trace[2032978296] 'range keys from in-memory index tree' (duration: 117.1411ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:49:37.063317Z","caller":"traceutil/trace.go:171","msg":"trace[2013712177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1795; }","duration":"149.473498ms","start":"2026-04-17T18:49:36.913827Z","end":"2026-04-17T18:49:37.063301Z","steps":["trace[2013712177] 'range keys from in-memory index tree' (duration: 149.323126ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:50:26.770977Z","caller":"traceutil/trace.go:171","msg":"trace[2142276361] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"150.999108ms","start":"2026-04-17T18:50:26.619933Z","end":"2026-04-17T18:50:26.770932Z","steps":["trace[2142276361] 'process raft request' (duration: 96.561099ms)","trace[2142276361] 'compare' (duration: 54.117903ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:50:27.140704Z","caller":"traceutil/trace.go:171","msg":"trace[1166850712] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"164.753255ms","start":"2026-04-17T18:50:26.975918Z","end":"2026-04-17T18:50:27.140671Z","steps":["trace[1166850712] 'process raft request' (duration: 164.49044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:55.996532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.471446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-17T18:50:55.996699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.096589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T18:50:55.996787Z","caller":"traceutil/trace.go:171","msg":"trace[1879537789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2184; }","duration":"163.22662ms","start":"2026-04-17T18:50:55.833541Z","end":"2026-04-17T18:50:55.996767Z","steps":["trace[1879537789] 'range keys from in-memory index tree' (duration: 162.909844ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:50:55.996635Z","caller":"traceutil/trace.go:171","msg":"trace[143785958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2184; }","duration":"276.622468ms","start":"2026-04-17T18:50:55.719992Z","end":"2026-04-17T18:50:55.996615Z","steps":["trace[143785958] 'range keys from in-memory index tree' (duration: 276.223321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:55.996839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.388184ms","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:531"} {"level":"info","ts":"2026-04-17T18:50:55.996877Z","caller":"traceutil/trace.go:171","msg":"trace[142182565] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2184; }","duration":"161.453875ms","start":"2026-04-17T18:50:55.835414Z","end":"2026-04-17T18:50:55.996868Z","steps":["trace[142182565] 'range keys from in-memory index tree' (duration: 161.072199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:55.996981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.253101ms","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-04-17T18:50:55.99649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.607143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-04-17T18:50:55.997003Z","caller":"traceutil/trace.go:171","msg":"trace[1475254497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2184; }","duration":"532.317973ms","start":"2026-04-17T18:50:55.464677Z","end":"2026-04-17T18:50:55.996995Z","steps":["trace[1475254497] 'range keys from in-memory index tree' (duration: 531.689681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:55.997022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:55.464653Z","time spent":"532.363264ms","remote":"127.0.0.1:41748","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-04-17T18:50:55.99711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.17959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-17T18:50:55.99719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.793363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T18:50:55.99737Z","caller":"traceutil/trace.go:171","msg":"trace[1833786323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2184; }","duration":"160.249392ms","start":"2026-04-17T18:50:55.836895Z","end":"2026-04-17T18:50:55.997144Z","steps":["trace[1833786323] 'range keys from in-memory index tree' (duration: 159.428296ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:50:55.997404Z","caller":"traceutil/trace.go:171","msg":"trace[1705034889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2184; }","duration":"345.855445ms","start":"2026-04-17T18:50:55.651355Z","end":"2026-04-17T18:50:55.997211Z","steps":["trace[1705034889] 'range keys from in-memory index tree' (duration: 344.878385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:55.997461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:55.651334Z","time spent":"346.115639ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T18:50:55.997574Z","caller":"traceutil/trace.go:171","msg":"trace[43847399] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2184; }","duration":"455.214315ms","start":"2026-04-17T18:50:55.5418Z","end":"2026-04-17T18:50:55.997014Z","steps":["trace[43847399] 'range keys from in-memory index tree' (duration: 454.405309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:55.997612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:55.541777Z","time spent":"455.825946ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-17T18:51:02.146056Z","caller":"traceutil/trace.go:171","msg":"trace[1260216725] linearizableReadLoop","detail":"{readStateIndex:2309; appliedIndex:2308; }","duration":"109.81803ms","start":"2026-04-17T18:51:02.036212Z","end":"2026-04-17T18:51:02.14603Z","steps":["trace[1260216725] 'read index received' (duration: 12.42313ms)","trace[1260216725] 'applied index is now lower than readState.Index' (duration: 97.39345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:51:02.146392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.168916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T18:51:02.14661Z","caller":"traceutil/trace.go:171","msg":"trace[1330185271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2216; }","duration":"110.429152ms","start":"2026-04-17T18:51:02.036164Z","end":"2026-04-17T18:51:02.146593Z","steps":["trace[1330185271] 'agreement among raft nodes before linearized reading' (duration: 110.034484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:02.146712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.30886ms","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:530"} {"level":"info","ts":"2026-04-17T18:51:02.146789Z","caller":"traceutil/trace.go:171","msg":"trace[14890616] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2216; }","duration":"108.539884ms","start":"2026-04-17T18:51:02.038235Z","end":"2026-04-17T18:51:02.146775Z","steps":["trace[14890616] 'agreement among raft nodes before linearized reading' (duration: 108.009334ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:02.27859Z","caller":"traceutil/trace.go:171","msg":"trace[332326733] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"126.830037ms","start":"2026-04-17T18:51:02.15174Z","end":"2026-04-17T18:51:02.27857Z","steps":["trace[332326733] 'process raft request' (duration: 126.690835ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:02.287849Z","caller":"traceutil/trace.go:171","msg":"trace[337396536] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"135.922513ms","start":"2026-04-17T18:51:02.151909Z","end":"2026-04-17T18:51:02.287831Z","steps":["trace[337396536] 'process raft request' (duration: 135.73999ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:02.287899Z","caller":"traceutil/trace.go:171","msg":"trace[53629646] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"135.554086ms","start":"2026-04-17T18:51:02.152325Z","end":"2026-04-17T18:51:02.287879Z","steps":["trace[53629646] 'process raft request' (duration: 135.501275ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:02.287861Z","caller":"traceutil/trace.go:171","msg":"trace[817585707] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"135.660028ms","start":"2026-04-17T18:51:02.152191Z","end":"2026-04-17T18:51:02.287851Z","steps":["trace[817585707] 'process raft request' (duration: 135.564016ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:02.288085Z","caller":"traceutil/trace.go:171","msg":"trace[1171997554] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"135.838182ms","start":"2026-04-17T18:51:02.152229Z","end":"2026-04-17T18:51:02.288067Z","steps":["trace[1171997554] 'process raft request' (duration: 135.552676ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:02.289753Z","caller":"traceutil/trace.go:171","msg":"trace[1608894812] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"135.80756ms","start":"2026-04-17T18:51:02.152292Z","end":"2026-04-17T18:51:02.2881Z","steps":["trace[1608894812] 'process raft request' (duration: 135.512175ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:03.63797Z","caller":"traceutil/trace.go:171","msg":"trace[1941245317] linearizableReadLoop","detail":"{readStateIndex:2316; appliedIndex:2315; }","duration":"255.339607ms","start":"2026-04-17T18:51:03.382605Z","end":"2026-04-17T18:51:03.637945Z","steps":["trace[1941245317] 'read index received' (duration: 255.113203ms)","trace[1941245317] 'applied index is now lower than readState.Index' (duration: 225.184µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:51:03.638062Z","caller":"traceutil/trace.go:171","msg":"trace[237518870] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"316.133171ms","start":"2026-04-17T18:51:03.321876Z","end":"2026-04-17T18:51:03.63801Z","steps":["trace[237518870] 'process raft request' (duration: 315.861446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:03.638255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.608492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:1 size:2025"} {"level":"warn","ts":"2026-04-17T18:51:03.638353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:03.321849Z","time spent":"316.378235ms","remote":"127.0.0.1:41748","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-04-17T18:51:03.638384Z","caller":"traceutil/trace.go:171","msg":"trace[884694569] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:1; response_revision:2223; }","duration":"255.777667ms","start":"2026-04-17T18:51:03.382574Z","end":"2026-04-17T18:51:03.638352Z","steps":["trace[884694569] 'agreement among raft nodes before linearized reading' (duration: 255.524291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:03.638421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.385118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:1 size:840"} {"level":"info","ts":"2026-04-17T18:51:03.638569Z","caller":"traceutil/trace.go:171","msg":"trace[1214598422] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:2223; }","duration":"242.536461ms","start":"2026-04-17T18:51:03.395968Z","end":"2026-04-17T18:51:03.638504Z","steps":["trace[1214598422] 'agreement among raft nodes before linearized reading' (duration: 242.312406ms)"],"step_count":1}