{"level":"warn","ts":"2026-06-24T11:06:59.326253Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-24T11:06:59.326406Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.140: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.140:2380","--initial-cluster=instance=https://199.19.213.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.140: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-06-24T11:06:59.326638Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-24T11:06:59.32672Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.140:2380"]} {"level":"info","ts":"2026-06-24T11:06:59.326745Z","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-06-24T11:06:59.327564Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"]} {"level":"info","ts":"2026-06-24T11:06:59.327971Z","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.140:2380"],"listen-peer-urls":["https://199.19.213.140:2380"],"advertise-client-urls":["https://199.19.213.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.140: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-06-24T11:06:59.330151Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.844642ms"} {"level":"info","ts":"2026-06-24T11:06:59.33625Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6366444c8cd268d","cluster-id":"b92fe020fa22927c"} {"level":"info","ts":"2026-06-24T11:06:59.336611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=()"} {"level":"info","ts":"2026-06-24T11:06:59.336678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became follower at term 0"} {"level":"info","ts":"2026-06-24T11:06:59.336704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6366444c8cd268d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-24T11:06:59.336759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became follower at term 1"} {"level":"info","ts":"2026-06-24T11:06:59.336827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=(13129792010234701453)"} {"level":"warn","ts":"2026-06-24T11:06:59.342636Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-24T11:06:59.344025Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-24T11:06:59.345175Z","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-06-24T11:06:59.34711Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6366444c8cd268d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-24T11:06:59.348434Z","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-06-24T11:06:59.347514Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6366444c8cd268d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-24T11:06:59.34863Z","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-06-24T11:06:59.34866Z","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-06-24T11:06:59.348902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=(13129792010234701453)"} {"level":"info","ts":"2026-06-24T11:06:59.349092Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b92fe020fa22927c","local-member-id":"b6366444c8cd268d","added-peer-id":"b6366444c8cd268d","added-peer-peer-urls":["https://199.19.213.140:2380"]} {"level":"info","ts":"2026-06-24T11:06:59.352694Z","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-06-24T11:06:59.352802Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.140:2380"} {"level":"info","ts":"2026-06-24T11:06:59.352847Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.140:2380"} {"level":"info","ts":"2026-06-24T11:06:59.353172Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6366444c8cd268d","initial-advertise-peer-urls":["https://199.19.213.140:2380"],"listen-peer-urls":["https://199.19.213.140:2380"],"advertise-client-urls":["https://199.19.213.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-24T11:06:59.35322Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-24T11:07:00.337165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d is starting a new election at term 1"} {"level":"info","ts":"2026-06-24T11:07:00.337247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-24T11:07:00.337279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d received MsgPreVoteResp from b6366444c8cd268d at term 1"} {"level":"info","ts":"2026-06-24T11:07:00.337297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became candidate at term 2"} {"level":"info","ts":"2026-06-24T11:07:00.337306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d received MsgVoteResp from b6366444c8cd268d at term 2"} {"level":"info","ts":"2026-06-24T11:07:00.337511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became leader at term 2"} {"level":"info","ts":"2026-06-24T11:07:00.337527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6366444c8cd268d elected leader b6366444c8cd268d at term 2"} {"level":"info","ts":"2026-06-24T11:07:00.33881Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6366444c8cd268d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.140:2379]}","request-path":"/0/members/b6366444c8cd268d/attributes","cluster-id":"b92fe020fa22927c","publish-timeout":"7s"} {"level":"info","ts":"2026-06-24T11:07:00.338921Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T11:07:00.339121Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-24T11:07:00.339169Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-24T11:07:00.339112Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T11:07:00.34049Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b92fe020fa22927c","local-member-id":"b6366444c8cd268d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T11:07:00.340596Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T11:07:00.340643Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T11:07:00.342471Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.140:2379"} {"level":"info","ts":"2026-06-24T11:07:00.338863Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T11:07:00.346018Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-24T11:07:01.873019Z","caller":"traceutil/trace.go:171","msg":"trace[2066195710] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"282.488115ms","start":"2026-06-24T11:07:01.590502Z","end":"2026-06-24T11:07:01.87299Z","steps":["trace[2066195710] 'read index received' (duration: 282.386693ms)","trace[2066195710] 'applied index is now lower than readState.Index' (duration: 100.222µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:07:01.873078Z","caller":"traceutil/trace.go:171","msg":"trace[181492892] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"283.065518ms","start":"2026-06-24T11:07:01.589979Z","end":"2026-06-24T11:07:01.873045Z","steps":["trace[181492892] 'process raft request' (duration: 282.794922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:01.873362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.832853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-24T11:07:01.873452Z","caller":"traceutil/trace.go:171","msg":"trace[644453464] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:2; }","duration":"282.960735ms","start":"2026-06-24T11:07:01.590467Z","end":"2026-06-24T11:07:01.873428Z","steps":["trace[644453464] 'agreement among raft nodes before linearized reading' (duration: 282.670698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:02.373641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778051338780903805,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T11:07:02.874248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778051338780903805,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T11:07:02.940064Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.067215119s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T11:07:02.940506Z","caller":"traceutil/trace.go:171","msg":"trace[1664448220] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.275611822s","start":"2026-06-24T11:07:01.664864Z","end":"2026-06-24T11:07:02.940476Z","steps":["trace[1664448220] 'process raft request' (duration: 1.275410087s)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:07:02.940549Z","caller":"traceutil/trace.go:171","msg":"trace[1875601547] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.264702458s","start":"2026-06-24T11:07:01.675828Z","end":"2026-06-24T11:07:02.940531Z","steps":["trace[1875601547] 'process raft request' (duration: 1.264678667s)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:07:02.940853Z","caller":"traceutil/trace.go:171","msg":"trace[753556239] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.27552573s","start":"2026-06-24T11:07:01.665319Z","end":"2026-06-24T11:07:02.940845Z","steps":["trace[753556239] 'process raft request' (duration: 1.275061759s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:02.940922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.664856Z","time spent":"1.275739574s","remote":"127.0.0.1:51046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T11:07:02.940974Z","caller":"traceutil/trace.go:171","msg":"trace[121386621] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.275563271s","start":"2026-06-24T11:07:01.665404Z","end":"2026-06-24T11:07:02.940968Z","steps":["trace[121386621] 'process raft request' (duration: 1.275019658s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:02.941002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.665397Z","time spent":"1.275592691s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T11:07:02.941146Z","caller":"traceutil/trace.go:171","msg":"trace[157545536] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.275689934s","start":"2026-06-24T11:07:01.665444Z","end":"2026-06-24T11:07:02.941134Z","steps":["trace[157545536] 'process raft request' (duration: 1.275007998s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:02.941201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.665441Z","time spent":"1.275736965s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T11:07:02.941303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.67582Z","time spent":"1.264833321s","remote":"127.0.0.1:50856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T11:07:02.941343Z","caller":"traceutil/trace.go:171","msg":"trace[1601974807] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.275836818s","start":"2026-06-24T11:07:01.665494Z","end":"2026-06-24T11:07:02.941331Z","steps":["trace[1601974807] 'process raft request' (duration: 1.274977158s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:02.940933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.665294Z","time spent":"1.275581051s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T11:07:02.941395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.665474Z","time spent":"1.275898599s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T11:07:02.941465Z","caller":"traceutil/trace.go:171","msg":"trace[1019866776] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.275897479s","start":"2026-06-24T11:07:01.665561Z","end":"2026-06-24T11:07:02.941459Z","steps":["trace[1019866776] 'process raft request' (duration: 1.274927826s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:02.941497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.66555Z","time spent":"1.27593448s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T11:07:03.002442Z","caller":"traceutil/trace.go:171","msg":"trace[845128716] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"1.129306122s","start":"2026-06-24T11:07:01.873117Z","end":"2026-06-24T11:07:03.002423Z","steps":["trace[845128716] 'read index received' (duration: 1.067149928s)","trace[845128716] 'applied index is now lower than readState.Index' (duration: 62.155194ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:07:03.002535Z","caller":"traceutil/trace.go:171","msg":"trace[1122672846] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.128213496s","start":"2026-06-24T11:07:01.874313Z","end":"2026-06-24T11:07:03.002526Z","steps":["trace[1122672846] 'process raft request' (duration: 1.125321969s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.002788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.874289Z","time spent":"1.128269817s","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:07:03.003005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411802237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-24T11:07:03.003007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370268923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-24T11:07:03.003092Z","caller":"traceutil/trace.go:171","msg":"trace[280051629] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.370371325s","start":"2026-06-24T11:07:01.6327Z","end":"2026-06-24T11:07:03.003071Z","steps":["trace[280051629] 'agreement among raft nodes before linearized reading' (duration: 1.370144189s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.003136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412531714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-24T11:07:03.003147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.632679Z","time spent":"1.370458956s","remote":"127.0.0.1:50692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-24T11:07:03.003167Z","caller":"traceutil/trace.go:171","msg":"trace[849546088] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:12; }","duration":"1.412580816s","start":"2026-06-24T11:07:01.590576Z","end":"2026-06-24T11:07:03.003156Z","steps":["trace[849546088] 'agreement among raft nodes before linearized reading' (duration: 1.412500664s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.003207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.590552Z","time spent":"1.412649038s","remote":"127.0.0.1:50652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-06-24T11:07:03.003443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366661278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-24T11:07:03.003023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340576533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.140\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-24T11:07:03.003483Z","caller":"traceutil/trace.go:171","msg":"trace[1973072601] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"1.366732629s","start":"2026-06-24T11:07:01.636741Z","end":"2026-06-24T11:07:03.003473Z","steps":["trace[1973072601] 'agreement among raft nodes before linearized reading' (duration: 1.366670148s)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:07:03.003498Z","caller":"traceutil/trace.go:171","msg":"trace[1254182317] range","detail":"{range_begin:/registry/masterleases/199.19.213.140; range_end:; response_count:0; response_revision:11; }","duration":"1.341116684s","start":"2026-06-24T11:07:01.662372Z","end":"2026-06-24T11:07:03.003489Z","steps":["trace[1254182317] 'agreement among raft nodes before linearized reading' (duration: 1.340421638s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.003543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.662354Z","time spent":"1.341179216s","remote":"127.0.0.1:50628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.19.213.140\" "} {"level":"info","ts":"2026-06-24T11:07:03.003641Z","caller":"traceutil/trace.go:171","msg":"trace[1044950477] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.120114859s","start":"2026-06-24T11:07:01.883365Z","end":"2026-06-24T11:07:03.00348Z","steps":["trace[1044950477] 'process raft request' (duration: 1.119000433s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.003769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37102902s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-24T11:07:03.003808Z","caller":"traceutil/trace.go:171","msg":"trace[1805736591] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:13; }","duration":"1.371099132s","start":"2026-06-24T11:07:01.6327Z","end":"2026-06-24T11:07:03.003799Z","steps":["trace[1805736591] 'agreement among raft nodes before linearized reading' (duration: 1.370965108s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.003835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.632677Z","time spent":"1.371151822s","remote":"127.0.0.1:50692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-24T11:07:03.003047Z","caller":"traceutil/trace.go:171","msg":"trace[1296323805] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.411851238s","start":"2026-06-24T11:07:01.591186Z","end":"2026-06-24T11:07:03.003037Z","steps":["trace[1296323805] 'agreement among raft nodes before linearized reading' (duration: 1.411791757s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.003934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.883346Z","time spent":"1.120447276s","remote":"127.0.0.1:50772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:07:03.00399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.59118Z","time spent":"1.41279986s","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-06-24T11:07:03.004199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287914448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-24T11:07:03.004247Z","caller":"traceutil/trace.go:171","msg":"trace[439264790] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:14; }","duration":"1.28796068s","start":"2026-06-24T11:07:01.716275Z","end":"2026-06-24T11:07:03.004236Z","steps":["trace[439264790] 'agreement among raft nodes before linearized reading' (duration: 1.287694213s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.004219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338456933s","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":"warn","ts":"2026-06-24T11:07:03.004318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339635909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-24T11:07:03.00435Z","caller":"traceutil/trace.go:171","msg":"trace[378866333] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:14; }","duration":"1.339681341s","start":"2026-06-24T11:07:01.66466Z","end":"2026-06-24T11:07:03.004342Z","steps":["trace[378866333] 'agreement among raft nodes before linearized reading' (duration: 1.339320733s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.00351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.636724Z","time spent":"1.366779901s","remote":"127.0.0.1:50692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-24T11:07:03.004385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.664647Z","time spent":"1.339730122s","remote":"127.0.0.1:50692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-24T11:07:03.004285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.71626Z","time spent":"1.2880182s","remote":"127.0.0.1:50692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-24T11:07:03.004522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413496067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-06-24T11:07:03.004346Z","caller":"traceutil/trace.go:171","msg":"trace[425915626] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:14; }","duration":"1.338614666s","start":"2026-06-24T11:07:01.665703Z","end":"2026-06-24T11:07:03.004318Z","steps":["trace[425915626] 'agreement among raft nodes before linearized reading' (duration: 1.338254978s)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:07:03.004566Z","caller":"traceutil/trace.go:171","msg":"trace[1897516805] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:14; }","duration":"1.413587449s","start":"2026-06-24T11:07:01.590969Z","end":"2026-06-24T11:07:03.004557Z","steps":["trace[1897516805] 'agreement among raft nodes before linearized reading' (duration: 1.412099045s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.0046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.590962Z","time spent":"1.41363172s","remote":"127.0.0.1:50772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-24T11:07:03.005808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:01.665698Z","time spent":"1.340073521s","remote":"127.0.0.1:50666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-06-24T11:07:03.006017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.461439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-24T11:07:03.006068Z","caller":"traceutil/trace.go:171","msg":"trace[1803467359] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:17; }","duration":"988.545461ms","start":"2026-06-24T11:07:02.017513Z","end":"2026-06-24T11:07:03.006058Z","steps":["trace[1803467359] 'agreement among raft nodes before linearized reading' (duration: 988.449459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.006104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:02.017493Z","time spent":"988.602832ms","remote":"127.0.0.1:50692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-24T11:07:03.006322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.276294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-24T11:07:03.006358Z","caller":"traceutil/trace.go:171","msg":"trace[58177239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17; }","duration":"169.335675ms","start":"2026-06-24T11:07:02.837011Z","end":"2026-06-24T11:07:03.006347Z","steps":["trace[58177239] 'agreement among raft nodes before linearized reading' (duration: 169.289114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.006462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.847798ms","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-06-24T11:07:03.006549Z","caller":"traceutil/trace.go:171","msg":"trace[591697169] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:17; }","duration":"441.987441ms","start":"2026-06-24T11:07:02.564538Z","end":"2026-06-24T11:07:03.006525Z","steps":["trace[591697169] 'agreement among raft nodes before linearized reading' (duration: 441.859988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.006589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:02.564508Z","time spent":"442.074752ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-06-24T11:07:03.006595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.258414ms","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-06-24T11:07:03.006663Z","caller":"traceutil/trace.go:171","msg":"trace[462875044] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:17; }","duration":"441.388207ms","start":"2026-06-24T11:07:02.565258Z","end":"2026-06-24T11:07:03.006646Z","steps":["trace[462875044] 'agreement among raft nodes before linearized reading' (duration: 441.291884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:03.006709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:02.565178Z","time spent":"441.52115ms","remote":"127.0.0.1:50970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-06-24T11:07:39.063207Z","caller":"traceutil/trace.go:171","msg":"trace[915471960] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"289.345322ms","start":"2026-06-24T11:07:38.773835Z","end":"2026-06-24T11:07:39.06318Z","steps":["trace[915471960] 'process raft request' (duration: 288.558994ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:07:39.095593Z","caller":"traceutil/trace.go:171","msg":"trace[338649156] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"258.971365ms","start":"2026-06-24T11:07:38.83648Z","end":"2026-06-24T11:07:39.095451Z","steps":["trace[338649156] 'process raft request' (duration: 258.787531ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:07:39.100581Z","caller":"traceutil/trace.go:171","msg":"trace[1671281893] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"244.010188ms","start":"2026-06-24T11:07:38.856555Z","end":"2026-06-24T11:07:39.100566Z","steps":["trace[1671281893] 'read index received' (duration: 205.912012ms)","trace[1671281893] 'applied index is now lower than readState.Index' (duration: 38.097586ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:07:39.100965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.406126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:07:39.100991Z","caller":"traceutil/trace.go:171","msg":"trace[2091741976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"244.478318ms","start":"2026-06-24T11:07:38.856506Z","end":"2026-06-24T11:07:39.100985Z","steps":["trace[2091741976] 'agreement among raft nodes before linearized reading' (duration: 244.433757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:41.303754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.245576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T11:07:41.303844Z","caller":"traceutil/trace.go:171","msg":"trace[1747869878] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"725.710725ms","start":"2026-06-24T11:07:40.578121Z","end":"2026-06-24T11:07:41.303832Z","steps":["trace[1747869878] 'process raft request' (duration: 618.204913ms)","trace[1747869878] 'compare' (duration: 106.752544ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:07:41.303885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:40.578098Z","time spent":"725.768997ms","remote":"127.0.0.1:50676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036165,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:07:41.304898Z","caller":"traceutil/trace.go:171","msg":"trace[994583892] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"470.360115ms","start":"2026-06-24T11:07:40.834499Z","end":"2026-06-24T11:07:41.304859Z","steps":["trace[994583892] 'read index received' (duration: 356.602317ms)","trace[994583892] 'applied index is now lower than readState.Index' (duration: 113.757068ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:07:41.304979Z","caller":"traceutil/trace.go:171","msg":"trace[856617103] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"641.257391ms","start":"2026-06-24T11:07:40.663698Z","end":"2026-06-24T11:07:41.304955Z","steps":["trace[856617103] 'process raft request' (duration: 640.987155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:41.305072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:40.663673Z","time spent":"641.354613ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T11:07:41.305076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.262773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:07:41.305158Z","caller":"traceutil/trace.go:171","msg":"trace[935027336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"446.413387ms","start":"2026-06-24T11:07:40.858719Z","end":"2026-06-24T11:07:41.305133Z","steps":["trace[935027336] 'agreement among raft nodes before linearized reading' (duration: 446.284874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:41.305204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:40.858698Z","time spent":"446.494748ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T11:07:41.305221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.214011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"warn","ts":"2026-06-24T11:07:41.305242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.717923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-06-24T11:07:41.305254Z","caller":"traceutil/trace.go:171","msg":"trace[322067659] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:642; }","duration":"271.285293ms","start":"2026-06-24T11:07:41.03396Z","end":"2026-06-24T11:07:41.305245Z","steps":["trace[322067659] 'agreement among raft nodes before linearized reading' (duration: 271.17512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:41.305248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.770485ms","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-06-24T11:07:41.305267Z","caller":"traceutil/trace.go:171","msg":"trace[677825590] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:642; }","duration":"470.800805ms","start":"2026-06-24T11:07:40.834458Z","end":"2026-06-24T11:07:41.305259Z","steps":["trace[677825590] 'agreement among raft nodes before linearized reading' (duration: 470.508748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:41.305301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:40.83444Z","time spent":"470.855666ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-06-24T11:07:41.305316Z","caller":"traceutil/trace.go:171","msg":"trace[2027808348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"238.860288ms","start":"2026-06-24T11:07:41.066435Z","end":"2026-06-24T11:07:41.305295Z","steps":["trace[2027808348] 'agreement among raft nodes before linearized reading' (duration: 238.642533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:41.305783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.081676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-24T11:07:41.305837Z","caller":"traceutil/trace.go:171","msg":"trace[863283971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:642; }","duration":"194.177618ms","start":"2026-06-24T11:07:41.111644Z","end":"2026-06-24T11:07:41.305821Z","steps":["trace[863283971] 'agreement among raft nodes before linearized reading' (duration: 193.40583ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:07:44.553678Z","caller":"traceutil/trace.go:171","msg":"trace[247148705] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"125.582243ms","start":"2026-06-24T11:07:44.428077Z","end":"2026-06-24T11:07:44.553659Z","steps":["trace[247148705] 'process raft request' (duration: 58.974992ms)","trace[247148705] 'compare' (duration: 66.414696ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:07:45.58709Z","caller":"traceutil/trace.go:171","msg":"trace[2000395330] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"245.80014ms","start":"2026-06-24T11:07:45.341264Z","end":"2026-06-24T11:07:45.587064Z","steps":["trace[2000395330] 'read index received' (duration: 245.702908ms)","trace[2000395330] 'applied index is now lower than readState.Index' (duration: 96.162µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:07:45.587217Z","caller":"traceutil/trace.go:171","msg":"trace[2076255748] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"256.579211ms","start":"2026-06-24T11:07:45.330606Z","end":"2026-06-24T11:07:45.587185Z","steps":["trace[2076255748] 'process raft request' (duration: 256.303454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:45.587357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.076186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-24T11:07:45.587438Z","caller":"traceutil/trace.go:171","msg":"trace[1453791895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:677; }","duration":"246.212659ms","start":"2026-06-24T11:07:45.341209Z","end":"2026-06-24T11:07:45.587422Z","steps":["trace[1453791895] 'agreement among raft nodes before linearized reading' (duration: 245.987944ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:07:45.754778Z","caller":"traceutil/trace.go:171","msg":"trace[779604484] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"163.139336ms","start":"2026-06-24T11:07:45.591606Z","end":"2026-06-24T11:07:45.754745Z","steps":["trace[779604484] 'process raft request' (duration: 130.896296ms)","trace[779604484] 'compare' (duration: 32.109396ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:07:45.777775Z","caller":"traceutil/trace.go:171","msg":"trace[511627262] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"173.070657ms","start":"2026-06-24T11:07:45.604657Z","end":"2026-06-24T11:07:45.777728Z","steps":["trace[511627262] 'process raft request' (duration: 172.908943ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:46.357616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778051338780905182,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T11:07:46.560963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.834832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T11:07:46.561055Z","caller":"traceutil/trace.go:171","msg":"trace[1321553709] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"704.298807ms","start":"2026-06-24T11:07:45.856741Z","end":"2026-06-24T11:07:46.56104Z","steps":["trace[1321553709] 'read index received' (duration: 540.257761ms)","trace[1321553709] 'applied index is now lower than readState.Index' (duration: 164.039906ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:07:46.561134Z","caller":"traceutil/trace.go:171","msg":"trace[1107018444] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"737.623133ms","start":"2026-06-24T11:07:45.823489Z","end":"2026-06-24T11:07:46.561113Z","steps":["trace[1107018444] 'process raft request' (duration: 573.561216ms)","trace[1107018444] 'compare' (duration: 163.673508ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:07:46.561228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:45.82346Z","time spent":"737.715954ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:07:46.561261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.746502ms","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-06-24T11:07:46.561149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.41341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:07:46.561436Z","caller":"traceutil/trace.go:171","msg":"trace[1516524411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"704.732397ms","start":"2026-06-24T11:07:45.856692Z","end":"2026-06-24T11:07:46.561424Z","steps":["trace[1516524411] 'agreement among raft nodes before linearized reading' (duration: 704.42836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:46.561463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:45.856671Z","time spent":"704.785338ms","remote":"127.0.0.1:50588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T11:07:46.561447Z","caller":"traceutil/trace.go:171","msg":"trace[1151358978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"228.961187ms","start":"2026-06-24T11:07:46.332465Z","end":"2026-06-24T11:07:46.561426Z","steps":["trace[1151358978] 'agreement among raft nodes before linearized reading' (duration: 228.708851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:46.561578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.377221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-24T11:07:46.561655Z","caller":"traceutil/trace.go:171","msg":"trace[1511841309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"181.505374ms","start":"2026-06-24T11:07:46.380132Z","end":"2026-06-24T11:07:46.561637Z","steps":["trace[1511841309] 'agreement among raft nodes before linearized reading' (duration: 181.252847ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:47.131971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.37217ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:07:47.132166Z","caller":"traceutil/trace.go:171","msg":"trace[1933074056] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:680; }","duration":"227.589685ms","start":"2026-06-24T11:07:46.904549Z","end":"2026-06-24T11:07:47.132138Z","steps":["trace[1933074056] 'range keys from in-memory index tree' (duration: 227.342869ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:07:47.132344Z","caller":"traceutil/trace.go:171","msg":"trace[1164510374] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"566.378688ms","start":"2026-06-24T11:07:46.565936Z","end":"2026-06-24T11:07:47.132315Z","steps":["trace[1164510374] 'process raft request' (duration: 493.43326ms)","trace[1164510374] 'compare' (duration: 72.702802ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:07:47.132567Z","caller":"traceutil/trace.go:171","msg":"trace[183698122] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:705; }","duration":"273.650496ms","start":"2026-06-24T11:07:46.858893Z","end":"2026-06-24T11:07:47.132544Z","steps":["trace[183698122] 'read index received' (duration: 200.562015ms)","trace[183698122] 'applied index is now lower than readState.Index' (duration: 73.087531ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:07:47.132603Z","caller":"traceutil/trace.go:171","msg":"trace[2058218076] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"565.050997ms","start":"2026-06-24T11:07:46.567537Z","end":"2026-06-24T11:07:47.132588Z","steps":["trace[2058218076] 'process raft request' (duration: 564.717549ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:07:47.132717Z","caller":"traceutil/trace.go:171","msg":"trace[115864847] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"565.006636ms","start":"2026-06-24T11:07:46.5677Z","end":"2026-06-24T11:07:47.132707Z","steps":["trace[115864847] 'process raft request' (duration: 564.76447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:47.132677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.76586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T11:07:47.132785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:46.567514Z","time spent":"565.20049ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:07:47.132803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:46.567693Z","time spent":"565.058757ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:07:47.132836Z","caller":"traceutil/trace.go:171","msg":"trace[120583350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"273.941033ms","start":"2026-06-24T11:07:46.858853Z","end":"2026-06-24T11:07:47.132794Z","steps":["trace[120583350] 'agreement among raft nodes before linearized reading' (duration: 273.782739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:07:47.133854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:07:46.565916Z","time spent":"566.508161ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:08:07.350107Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778051338780905437,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-24T11:08:07.634045Z","caller":"traceutil/trace.go:171","msg":"trace[1873937647] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"975.348834ms","start":"2026-06-24T11:08:06.658672Z","end":"2026-06-24T11:08:07.634021Z","steps":["trace[1873937647] 'process raft request' (duration: 975.195231ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:07.634305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:06.658656Z","time spent":"975.465677ms","remote":"127.0.0.1:50780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:08:07.679359Z","caller":"traceutil/trace.go:171","msg":"trace[1099135673] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:834; }","duration":"829.682805ms","start":"2026-06-24T11:08:06.849656Z","end":"2026-06-24T11:08:07.679339Z","steps":["trace[1099135673] 'read index received' (duration: 784.242018ms)","trace[1099135673] 'applied index is now lower than readState.Index' (duration: 45.440097ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:08:07.679436Z","caller":"traceutil/trace.go:171","msg":"trace[1652653] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"766.70341ms","start":"2026-06-24T11:08:06.912715Z","end":"2026-06-24T11:08:07.679418Z","steps":["trace[1652653] 'process raft request' (duration: 766.464644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:07.679474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.804777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:08:07.679502Z","caller":"traceutil/trace.go:171","msg":"trace[138956477] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:806; }","duration":"829.867169ms","start":"2026-06-24T11:08:06.849623Z","end":"2026-06-24T11:08:07.67949Z","steps":["trace[138956477] 'agreement among raft nodes before linearized reading' (duration: 829.810668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:07.679522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:06.849605Z","time spent":"829.91059ms","remote":"127.0.0.1:51088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-24T11:08:07.679526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:06.912692Z","time spent":"766.774352ms","remote":"127.0.0.1:50772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:08:07.679735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.491709ms","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-06-24T11:08:07.679779Z","caller":"traceutil/trace.go:171","msg":"trace[382519280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"461.572591ms","start":"2026-06-24T11:08:07.218197Z","end":"2026-06-24T11:08:07.67977Z","steps":["trace[382519280] 'agreement among raft nodes before linearized reading' (duration: 461.410837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:07.679805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:07.218175Z","time spent":"461.623761ms","remote":"127.0.0.1:50872","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-06-24T11:08:07.679809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.990693ms","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-06-24T11:08:07.680003Z","caller":"traceutil/trace.go:171","msg":"trace[2016556124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"457.2702ms","start":"2026-06-24T11:08:07.222724Z","end":"2026-06-24T11:08:07.679994Z","steps":["trace[2016556124] 'agreement among raft nodes before linearized reading' (duration: 456.818559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:07.680101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:07.222699Z","time spent":"457.392762ms","remote":"127.0.0.1:50872","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-06-24T11:08:07.679871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.272171ms","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-06-24T11:08:07.680513Z","caller":"traceutil/trace.go:171","msg":"trace[677167006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:806; }","duration":"442.997247ms","start":"2026-06-24T11:08:07.237505Z","end":"2026-06-24T11:08:07.680503Z","steps":["trace[677167006] 'agreement among raft nodes before linearized reading' (duration: 442.094317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:07.680637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:07.237483Z","time spent":"443.146441ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-24T11:08:07.679975Z","caller":"traceutil/trace.go:171","msg":"trace[142141699] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"588.442292ms","start":"2026-06-24T11:08:07.091521Z","end":"2026-06-24T11:08:07.679964Z","steps":["trace[142141699] 'process raft request' (duration: 587.781887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:07.680875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:07.091495Z","time spent":"589.344052ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:08:07.679627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.950901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:08:07.681149Z","caller":"traceutil/trace.go:171","msg":"trace[2016792229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"822.547559ms","start":"2026-06-24T11:08:06.858587Z","end":"2026-06-24T11:08:07.681135Z","steps":["trace[2016792229] 'agreement among raft nodes before linearized reading' (duration: 820.930321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:07.681267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:06.858566Z","time spent":"822.691013ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T11:08:42.254545Z","caller":"traceutil/trace.go:171","msg":"trace[1539564100] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"258.509ms","start":"2026-06-24T11:08:41.996006Z","end":"2026-06-24T11:08:42.254515Z","steps":["trace[1539564100] 'read index received' (duration: 258.227384ms)","trace[1539564100] 'applied index is now lower than readState.Index' (duration: 280.376µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:08:42.254675Z","caller":"traceutil/trace.go:171","msg":"trace[309632886] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"281.439254ms","start":"2026-06-24T11:08:41.973194Z","end":"2026-06-24T11:08:42.254633Z","steps":["trace[309632886] 'process raft request' (duration: 281.149727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:42.254822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.65029ms","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-06-24T11:08:42.254863Z","caller":"traceutil/trace.go:171","msg":"trace[1142903342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"257.763973ms","start":"2026-06-24T11:08:41.997086Z","end":"2026-06-24T11:08:42.25485Z","steps":["trace[1142903342] 'agreement among raft nodes before linearized reading' (duration: 257.615759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:42.254922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.905722ms","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-06-24T11:08:42.254957Z","caller":"traceutil/trace.go:171","msg":"trace[2035593310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1021; }","duration":"256.941663ms","start":"2026-06-24T11:08:41.998005Z","end":"2026-06-24T11:08:42.254947Z","steps":["trace[2035593310] 'agreement among raft nodes before linearized reading' (duration: 256.855141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:42.255006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.042725ms","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-06-24T11:08:42.255024Z","caller":"traceutil/trace.go:171","msg":"trace[746762254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"257.078577ms","start":"2026-06-24T11:08:41.99794Z","end":"2026-06-24T11:08:42.255018Z","steps":["trace[746762254] 'agreement among raft nodes before linearized reading' (duration: 257.017015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:42.254895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.204207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-24T11:08:42.255041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.946909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-06-24T11:08:42.254821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.809707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-24T11:08:42.255059Z","caller":"traceutil/trace.go:171","msg":"trace[295957564] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1021; }","duration":"155.436802ms","start":"2026-06-24T11:08:42.099613Z","end":"2026-06-24T11:08:42.25505Z","steps":["trace[295957564] 'agreement among raft nodes before linearized reading' (duration: 155.212437ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:08:42.255076Z","caller":"traceutil/trace.go:171","msg":"trace[1309795760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"259.143195ms","start":"2026-06-24T11:08:41.995927Z","end":"2026-06-24T11:08:42.25507Z","steps":["trace[1309795760] 'agreement among raft nodes before linearized reading' (duration: 258.756616ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:08:42.255078Z","caller":"traceutil/trace.go:171","msg":"trace[348342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1021; }","duration":"105.023901ms","start":"2026-06-24T11:08:42.150038Z","end":"2026-06-24T11:08:42.255062Z","steps":["trace[348342] 'agreement among raft nodes before linearized reading' (duration: 104.905048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:42.692837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.179724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T11:08:42.692978Z","caller":"traceutil/trace.go:171","msg":"trace[414707511] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"434.049549ms","start":"2026-06-24T11:08:42.258905Z","end":"2026-06-24T11:08:42.692954Z","steps":["trace[414707511] 'process raft request' (duration: 239.64874ms)","trace[414707511] 'compare' (duration: 193.937758ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:08:42.693037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:42.258884Z","time spent":"434.125742ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:08:42.69528Z","caller":"traceutil/trace.go:171","msg":"trace[877390758] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"435.603335ms","start":"2026-06-24T11:08:42.259663Z","end":"2026-06-24T11:08:42.695266Z","steps":["trace[877390758] 'process raft request' (duration: 435.572655ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:08:42.695309Z","caller":"traceutil/trace.go:171","msg":"trace[1858776483] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"436.368883ms","start":"2026-06-24T11:08:42.258912Z","end":"2026-06-24T11:08:42.695281Z","steps":["trace[1858776483] 'process raft request' (duration: 436.125857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:42.695372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:42.259654Z","time spent":"435.677247ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:08:42.695439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:42.258889Z","time spent":"436.474986ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:08:42.695509Z","caller":"traceutil/trace.go:171","msg":"trace[746204179] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"436.509477ms","start":"2026-06-24T11:08:42.258991Z","end":"2026-06-24T11:08:42.695501Z","steps":["trace[746204179] 'process raft request' (duration: 436.181588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:42.695608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:42.258983Z","time spent":"436.60043ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:08:42.695773Z","caller":"traceutil/trace.go:171","msg":"trace[200538059] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"436.747203ms","start":"2026-06-24T11:08:42.259017Z","end":"2026-06-24T11:08:42.695764Z","steps":["trace[200538059] 'process raft request' (duration: 436.193179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:08:42.695818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:08:42.259003Z","time spent":"436.793333ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:09:06.404709Z","caller":"traceutil/trace.go:171","msg":"trace[998912276] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"418.458547ms","start":"2026-06-24T11:09:05.98622Z","end":"2026-06-24T11:09:06.404679Z","steps":["trace[998912276] 'process raft request' (duration: 418.295573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:06.404879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:05.986197Z","time spent":"418.595929ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:09:06.744456Z","caller":"traceutil/trace.go:171","msg":"trace[353704442] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"397.847748ms","start":"2026-06-24T11:09:06.346575Z","end":"2026-06-24T11:09:06.744423Z","steps":["trace[353704442] 'process raft request' (duration: 365.252281ms)","trace[353704442] 'compare' (duration: 32.424953ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:09:06.744605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:06.346545Z","time spent":"397.975961ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:09:07.249533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.421419ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:09:07.249749Z","caller":"traceutil/trace.go:171","msg":"trace[556420793] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1102; }","duration":"345.655475ms","start":"2026-06-24T11:09:06.904065Z","end":"2026-06-24T11:09:07.249721Z","steps":["trace[556420793] 'range keys from in-memory index tree' (duration: 345.396379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:07.249865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.228348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T11:09:07.250208Z","caller":"traceutil/trace.go:171","msg":"trace[413831918] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1146; }","duration":"444.963703ms","start":"2026-06-24T11:09:06.805028Z","end":"2026-06-24T11:09:07.249991Z","steps":["trace[413831918] 'read index received' (duration: 33.911028ms)","trace[413831918] 'applied index is now lower than readState.Index' (duration: 411.050885ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:09:07.250336Z","caller":"traceutil/trace.go:171","msg":"trace[1698740465] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"447.992714ms","start":"2026-06-24T11:09:06.802168Z","end":"2026-06-24T11:09:07.250161Z","steps":["trace[1698740465] 'process raft request' (duration: 55.342757ms)","trace[1698740465] 'compare' (duration: 391.877489ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:09:07.250399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.359002ms","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-06-24T11:09:07.250444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:06.802151Z","time spent":"448.2384ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:09:07.250501Z","caller":"traceutil/trace.go:171","msg":"trace[956321037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"445.463605ms","start":"2026-06-24T11:09:06.805001Z","end":"2026-06-24T11:09:07.250465Z","steps":["trace[956321037] 'agreement among raft nodes before linearized reading' (duration: 445.23142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:07.250552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:06.804991Z","time spent":"445.548707ms","remote":"127.0.0.1:50872","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-06-24T11:09:07.251168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.634723ms","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-06-24T11:09:07.251188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.045941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:09:07.251223Z","caller":"traceutil/trace.go:171","msg":"trace[735693514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"437.708184ms","start":"2026-06-24T11:09:06.813499Z","end":"2026-06-24T11:09:07.251207Z","steps":["trace[735693514] 'agreement among raft nodes before linearized reading' (duration: 437.52314ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:07.251241Z","caller":"traceutil/trace.go:171","msg":"trace[555908456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"237.141703ms","start":"2026-06-24T11:09:07.014083Z","end":"2026-06-24T11:09:07.251225Z","steps":["trace[555908456] 'agreement among raft nodes before linearized reading' (duration: 237.057481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:07.25127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:06.81349Z","time spent":"437.766755ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T11:09:07.251523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.393627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-24T11:09:07.251568Z","caller":"traceutil/trace.go:171","msg":"trace[384842879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1103; }","duration":"437.447618ms","start":"2026-06-24T11:09:06.814108Z","end":"2026-06-24T11:09:07.251556Z","steps":["trace[384842879] 'agreement among raft nodes before linearized reading' (duration: 437.334556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:07.251608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:06.814102Z","time spent":"437.497739ms","remote":"127.0.0.1:50872","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-06-24T11:09:07.596164Z","caller":"traceutil/trace.go:171","msg":"trace[1512443216] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"340.72355ms","start":"2026-06-24T11:09:07.255409Z","end":"2026-06-24T11:09:07.596132Z","steps":["trace[1512443216] 'process raft request' (duration: 334.145567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:07.596379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:07.255359Z","time spent":"340.879813ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:09:07.608237Z","caller":"traceutil/trace.go:171","msg":"trace[967488590] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1148; }","duration":"202.000596ms","start":"2026-06-24T11:09:07.406208Z","end":"2026-06-24T11:09:07.608208Z","steps":["trace[967488590] 'read index received' (duration: 183.324102ms)","trace[967488590] 'applied index is now lower than readState.Index' (duration: 18.675674ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:09:07.608327Z","caller":"traceutil/trace.go:171","msg":"trace[482606795] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"352.734429ms","start":"2026-06-24T11:09:07.255585Z","end":"2026-06-24T11:09:07.60832Z","steps":["trace[482606795] 'process raft request' (duration: 352.589565ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:07.608251Z","caller":"traceutil/trace.go:171","msg":"trace[2076578023] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"352.724379ms","start":"2026-06-24T11:09:07.255511Z","end":"2026-06-24T11:09:07.608236Z","steps":["trace[2076578023] 'process raft request' (duration: 352.553244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:07.608496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.00784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:09:07.608528Z","caller":"traceutil/trace.go:171","msg":"trace[1897934297] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1106; }","duration":"120.086091ms","start":"2026-06-24T11:09:07.488435Z","end":"2026-06-24T11:09:07.608521Z","steps":["trace[1897934297] 'agreement among raft nodes before linearized reading' (duration: 120.0308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:07.608642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:07.25548Z","time spent":"352.984605ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:09:07.608664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.428946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T11:09:07.608432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:07.255546Z","time spent":"352.80321ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:09:07.608775Z","caller":"traceutil/trace.go:171","msg":"trace[1199915042] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1106; }","duration":"202.609451ms","start":"2026-06-24T11:09:07.406142Z","end":"2026-06-24T11:09:07.608751Z","steps":["trace[1199915042] 'agreement among raft nodes before linearized reading' (duration: 202.387526ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:09.710533Z","caller":"traceutil/trace.go:171","msg":"trace[1272576187] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"107.98799ms","start":"2026-06-24T11:09:09.602501Z","end":"2026-06-24T11:09:09.710489Z","steps":["trace[1272576187] 'process raft request' (duration: 107.706494ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:10.97462Z","caller":"traceutil/trace.go:171","msg":"trace[1703010237] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"103.929796ms","start":"2026-06-24T11:09:10.87065Z","end":"2026-06-24T11:09:10.974579Z","steps":["trace[1703010237] 'process raft request' (duration: 64.590272ms)","trace[1703010237] 'compare' (duration: 39.18116ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:09:25.243837Z","caller":"traceutil/trace.go:171","msg":"trace[283802571] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"367.42118ms","start":"2026-06-24T11:09:24.876388Z","end":"2026-06-24T11:09:25.243809Z","steps":["trace[283802571] 'process raft request' (duration: 367.234206ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:25.243877Z","caller":"traceutil/trace.go:171","msg":"trace[1046806271] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1262; }","duration":"204.851781ms","start":"2026-06-24T11:09:25.038988Z","end":"2026-06-24T11:09:25.24384Z","steps":["trace[1046806271] 'read index received' (duration: 204.822101ms)","trace[1046806271] 'applied index is now lower than readState.Index' (duration: 27.01µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:09:25.243995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:24.876361Z","time spent":"367.551824ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:09:25.244152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.129158ms","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-06-24T11:09:25.244198Z","caller":"traceutil/trace.go:171","msg":"trace[732412261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"205.253561ms","start":"2026-06-24T11:09:25.038932Z","end":"2026-06-24T11:09:25.244185Z","steps":["trace[732412261] 'agreement among raft nodes before linearized reading' (duration: 205.021576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:27.353038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778051338780906358,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-24T11:09:27.559426Z","caller":"traceutil/trace.go:171","msg":"trace[1686542418] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"769.929476ms","start":"2026-06-24T11:09:26.789459Z","end":"2026-06-24T11:09:27.559388Z","steps":["trace[1686542418] 'process raft request' (duration: 769.704171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:27.559646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:26.789431Z","time spent":"770.096061ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:09:27.56807Z","caller":"traceutil/trace.go:171","msg":"trace[1272798383] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1267; }","duration":"715.683807ms","start":"2026-06-24T11:09:26.852351Z","end":"2026-06-24T11:09:27.568035Z","steps":["trace[1272798383] 'read index received' (duration: 706.893922ms)","trace[1272798383] 'applied index is now lower than readState.Index' (duration: 8.788845ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:09:27.568327Z","caller":"traceutil/trace.go:171","msg":"trace[165642909] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"319.089417ms","start":"2026-06-24T11:09:27.249224Z","end":"2026-06-24T11:09:27.568314Z","steps":["trace[165642909] 'process raft request' (duration: 318.709239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:27.568429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:27.249199Z","time spent":"319.17127ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:09:27.568615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.875928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:09:27.568701Z","caller":"traceutil/trace.go:171","msg":"trace[413591429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"708.994721ms","start":"2026-06-24T11:09:26.859687Z","end":"2026-06-24T11:09:27.568681Z","steps":["trace[413591429] 'agreement among raft nodes before linearized reading' (duration: 708.850478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:27.568747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:26.859676Z","time spent":"709.062832ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T11:09:27.568775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.564127ms","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-06-24T11:09:27.568818Z","caller":"traceutil/trace.go:171","msg":"trace[290215392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1218; }","duration":"189.66617ms","start":"2026-06-24T11:09:27.379143Z","end":"2026-06-24T11:09:27.568809Z","steps":["trace[290215392] 'agreement among raft nodes before linearized reading' (duration: 189.493896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:27.568911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.564367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:09:27.568942Z","caller":"traceutil/trace.go:171","msg":"trace[767722421] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1218; }","duration":"716.598468ms","start":"2026-06-24T11:09:26.852335Z","end":"2026-06-24T11:09:27.568934Z","steps":["trace[767722421] 'agreement among raft nodes before linearized reading' (duration: 716.546366ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:27.568963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:26.852281Z","time spent":"716.675639ms","remote":"127.0.0.1:49836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-24T11:09:27.569025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.88409ms","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-06-24T11:09:27.569061Z","caller":"traceutil/trace.go:171","msg":"trace[687077065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"314.944992ms","start":"2026-06-24T11:09:27.254107Z","end":"2026-06-24T11:09:27.569052Z","steps":["trace[687077065] 'agreement among raft nodes before linearized reading' (duration: 314.85364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:27.569087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:27.254099Z","time spent":"314.983133ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T11:09:27.569178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.422594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T11:09:27.569206Z","caller":"traceutil/trace.go:171","msg":"trace[50310310] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1218; }","duration":"374.452655ms","start":"2026-06-24T11:09:27.194745Z","end":"2026-06-24T11:09:27.569198Z","steps":["trace[50310310] 'agreement among raft nodes before linearized reading' (duration: 374.404034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:27.569234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:27.19467Z","time spent":"374.555347ms","remote":"127.0.0.1:33432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-06-24T11:09:40.003474Z","caller":"traceutil/trace.go:171","msg":"trace[1515812225] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"110.740405ms","start":"2026-06-24T11:09:39.892718Z","end":"2026-06-24T11:09:40.003458Z","steps":["trace[1515812225] 'read index received' (duration: 110.625042ms)","trace[1515812225] 'applied index is now lower than readState.Index' (duration: 114.723µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:09:40.003555Z","caller":"traceutil/trace.go:171","msg":"trace[1867440113] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"157.540663ms","start":"2026-06-24T11:09:39.846004Z","end":"2026-06-24T11:09:40.003545Z","steps":["trace[1867440113] 'process raft request' (duration: 157.360369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:40.003634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.913839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-24T11:09:40.003661Z","caller":"traceutil/trace.go:171","msg":"trace[429051425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1261; }","duration":"110.993201ms","start":"2026-06-24T11:09:39.892662Z","end":"2026-06-24T11:09:40.003655Z","steps":["trace[429051425] 'agreement among raft nodes before linearized reading' (duration: 110.883678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:40.003872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.110653ms","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-06-24T11:09:40.003958Z","caller":"traceutil/trace.go:171","msg":"trace[1398137720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1261; }","duration":"111.294997ms","start":"2026-06-24T11:09:39.892647Z","end":"2026-06-24T11:09:40.003942Z","steps":["trace[1398137720] 'agreement among raft nodes before linearized reading' (duration: 111.034511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:44.200092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778051338780906524,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T11:09:44.62962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.964446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T11:09:44.629708Z","caller":"traceutil/trace.go:171","msg":"trace[699645051] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"930.320525ms","start":"2026-06-24T11:09:43.699375Z","end":"2026-06-24T11:09:44.629695Z","steps":["trace[699645051] 'read index received' (duration: 589.161364ms)","trace[699645051] 'applied index is now lower than readState.Index' (duration: 341.158141ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:09:44.629729Z","caller":"traceutil/trace.go:171","msg":"trace[1479724586] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"948.538739ms","start":"2026-06-24T11:09:43.681164Z","end":"2026-06-24T11:09:44.629703Z","steps":["trace[1479724586] 'process raft request' (duration: 607.42777ms)","trace[1479724586] 'compare' (duration: 339.818819ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:09:44.629823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:43.681139Z","time spent":"948.636421ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:09:44.629848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.477559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-24T11:09:44.629869Z","caller":"traceutil/trace.go:171","msg":"trace[941404867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1291; }","duration":"930.536159ms","start":"2026-06-24T11:09:43.699326Z","end":"2026-06-24T11:09:44.629862Z","steps":["trace[941404867] 'agreement among raft nodes before linearized reading' (duration: 930.433987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:44.629892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:43.699301Z","time spent":"930.584931ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T11:09:44.629927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.163897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.140\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-24T11:09:44.629934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.561038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T11:09:44.629974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.468901ms","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-06-24T11:09:44.629994Z","caller":"traceutil/trace.go:171","msg":"trace[1857992414] range","detail":"{range_begin:/registry/masterleases/199.19.213.140; range_end:; response_count:1; response_revision:1291; }","duration":"904.27182ms","start":"2026-06-24T11:09:43.725704Z","end":"2026-06-24T11:09:44.629976Z","steps":["trace[1857992414] 'agreement among raft nodes before linearized reading' (duration: 904.100116ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:44.630002Z","caller":"traceutil/trace.go:171","msg":"trace[714919360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"772.66872ms","start":"2026-06-24T11:09:43.857314Z","end":"2026-06-24T11:09:44.629983Z","steps":["trace[714919360] 'agreement among raft nodes before linearized reading' (duration: 772.567958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:44.630005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.053291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-06-24T11:09:44.630037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:43.857291Z","time spent":"772.738512ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T11:09:44.630036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:43.725655Z","time spent":"904.372742ms","remote":"127.0.0.1:50628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.140\" "} {"level":"info","ts":"2026-06-24T11:09:44.630038Z","caller":"traceutil/trace.go:171","msg":"trace[916171070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1291; }","duration":"607.568433ms","start":"2026-06-24T11:09:44.022451Z","end":"2026-06-24T11:09:44.63002Z","steps":["trace[916171070] 'agreement among raft nodes before linearized reading' (duration: 607.386859ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:44.630083Z","caller":"traceutil/trace.go:171","msg":"trace[2105262984] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1291; }","duration":"607.158283ms","start":"2026-06-24T11:09:44.022904Z","end":"2026-06-24T11:09:44.630062Z","steps":["trace[2105262984] 'agreement among raft nodes before linearized reading' (duration: 606.928448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:44.630086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:44.022433Z","time spent":"607.642364ms","remote":"127.0.0.1:50872","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-06-24T11:09:44.63012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:44.022899Z","time spent":"607.214195ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-24T11:09:44.630481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.335549ms","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-06-24T11:09:44.630526Z","caller":"traceutil/trace.go:171","msg":"trace[1636050749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1291; }","duration":"614.403551ms","start":"2026-06-24T11:09:44.016108Z","end":"2026-06-24T11:09:44.630511Z","steps":["trace[1636050749] 'agreement among raft nodes before linearized reading' (duration: 614.297519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:44.630556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:44.016093Z","time spent":"614.455382ms","remote":"127.0.0.1:50872","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-06-24T11:09:44.882106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.158774ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-06-24T11:09:44.882215Z","caller":"traceutil/trace.go:171","msg":"trace[1847472818] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"250.047862ms","start":"2026-06-24T11:09:44.632152Z","end":"2026-06-24T11:09:44.8822Z","steps":["trace[1847472818] 'read index received' (duration: 135.768625ms)","trace[1847472818] 'applied index is now lower than readState.Index' (duration: 114.277957ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:09:44.882306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.145755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:09:44.882322Z","caller":"traceutil/trace.go:171","msg":"trace[1410128995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"250.180225ms","start":"2026-06-24T11:09:44.632137Z","end":"2026-06-24T11:09:44.882317Z","steps":["trace[1410128995] 'agreement among raft nodes before linearized reading' (duration: 250.132424ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:44.9097Z","caller":"traceutil/trace.go:171","msg":"trace[1496334490] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"274.643654ms","start":"2026-06-24T11:09:44.635036Z","end":"2026-06-24T11:09:44.90968Z","steps":["trace[1496334490] 'process raft request' (duration: 274.568533ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:44.909799Z","caller":"traceutil/trace.go:171","msg":"trace[1212004209] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"274.321547ms","start":"2026-06-24T11:09:44.635463Z","end":"2026-06-24T11:09:44.909784Z","steps":["trace[1212004209] 'process raft request' (duration: 274.158423ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:44.909707Z","caller":"traceutil/trace.go:171","msg":"trace[748276457] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"274.663695ms","start":"2026-06-24T11:09:44.63501Z","end":"2026-06-24T11:09:44.909673Z","steps":["trace[748276457] 'process raft request' (duration: 274.574603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:44.909892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.992881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:09:44.909942Z","caller":"traceutil/trace.go:171","msg":"trace[1543960418] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1295; }","duration":"127.108084ms","start":"2026-06-24T11:09:44.782824Z","end":"2026-06-24T11:09:44.909932Z","steps":["trace[1543960418] 'agreement among raft nodes before linearized reading' (duration: 126.971911ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:44.909768Z","caller":"traceutil/trace.go:171","msg":"trace[834973365] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"274.802788ms","start":"2026-06-24T11:09:44.634955Z","end":"2026-06-24T11:09:44.909758Z","steps":["trace[834973365] 'process raft request' (duration: 274.498211ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:45.899523Z","caller":"traceutil/trace.go:171","msg":"trace[1220929118] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"242.848614ms","start":"2026-06-24T11:09:45.656651Z","end":"2026-06-24T11:09:45.899499Z","steps":["trace[1220929118] 'process raft request' (duration: 242.275281ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:47.354065Z","caller":"traceutil/trace.go:171","msg":"trace[947916988] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"435.113827ms","start":"2026-06-24T11:09:46.918933Z","end":"2026-06-24T11:09:47.354047Z","steps":["trace[947916988] 'process raft request' (duration: 435.015864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:47.3542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:46.918928Z","time spent":"435.205889ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:09:47.354227Z","caller":"traceutil/trace.go:171","msg":"trace[1096069972] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"388.35774ms","start":"2026-06-24T11:09:46.965841Z","end":"2026-06-24T11:09:47.354198Z","steps":["trace[1096069972] 'process raft request' (duration: 388.266678ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:47.354332Z","caller":"traceutil/trace.go:171","msg":"trace[1677266647] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"434.451421ms","start":"2026-06-24T11:09:46.919858Z","end":"2026-06-24T11:09:47.354309Z","steps":["trace[1677266647] 'process raft request' (duration: 434.166195ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:47.354333Z","caller":"traceutil/trace.go:171","msg":"trace[1637850944] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"435.462385ms","start":"2026-06-24T11:09:46.918839Z","end":"2026-06-24T11:09:47.354301Z","steps":["trace[1637850944] 'process raft request' (duration: 413.438053ms)","trace[1637850944] 'compare' (duration: 21.439908ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:09:47.354367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:46.965816Z","time spent":"388.481313ms","remote":"127.0.0.1:50662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T11:09:47.354436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:46.919847Z","time spent":"434.530263ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:09:47.354478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:46.918818Z","time spent":"435.580039ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:09:48.047369Z","caller":"traceutil/trace.go:171","msg":"trace[470969624] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"143.585819ms","start":"2026-06-24T11:09:47.903759Z","end":"2026-06-24T11:09:48.047344Z","steps":["trace[470969624] 'process raft request' (duration: 143.396004ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:57.932149Z","caller":"traceutil/trace.go:171","msg":"trace[1060066853] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"218.046851ms","start":"2026-06-24T11:09:57.714077Z","end":"2026-06-24T11:09:57.932124Z","steps":["trace[1060066853] 'process raft request' (duration: 217.844605ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:58.398217Z","caller":"traceutil/trace.go:171","msg":"trace[1573060903] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"318.491396ms","start":"2026-06-24T11:09:58.079692Z","end":"2026-06-24T11:09:58.398184Z","steps":["trace[1573060903] 'process raft request' (duration: 249.971902ms)","trace[1573060903] 'compare' (duration: 68.387721ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:09:58.398738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:58.079668Z","time spent":"318.63046ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:09:59.255202Z","caller":"traceutil/trace.go:171","msg":"trace[770877150] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1437; }","duration":"969.596826ms","start":"2026-06-24T11:09:58.285581Z","end":"2026-06-24T11:09:59.255178Z","steps":["trace[770877150] 'read index received' (duration: 44.225269ms)","trace[770877150] 'applied index is now lower than readState.Index' (duration: 925.370137ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:09:59.255568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.095188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-06-24T11:09:59.2556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.984388ms","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-06-24T11:09:59.255646Z","caller":"traceutil/trace.go:171","msg":"trace[1915606206] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1382; }","duration":"644.243691ms","start":"2026-06-24T11:09:58.611383Z","end":"2026-06-24T11:09:59.255627Z","steps":["trace[1915606206] 'agreement among raft nodes before linearized reading' (duration: 644.033916ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:59.255655Z","caller":"traceutil/trace.go:171","msg":"trace[1940760934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1382; }","duration":"227.115531ms","start":"2026-06-24T11:09:59.028525Z","end":"2026-06-24T11:09:59.255641Z","steps":["trace[1940760934] 'agreement among raft nodes before linearized reading' (duration: 226.942347ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:09:59.25564Z","caller":"traceutil/trace.go:171","msg":"trace[1042074034] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"983.007988ms","start":"2026-06-24T11:09:58.272606Z","end":"2026-06-24T11:09:59.255613Z","steps":["trace[1042074034] 'process raft request' (duration: 982.426545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:59.255666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.771986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T11:09:59.255687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:58.61135Z","time spent":"644.328103ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-24T11:09:59.255748Z","caller":"traceutil/trace.go:171","msg":"trace[465503122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1382; }","duration":"396.869988ms","start":"2026-06-24T11:09:58.858835Z","end":"2026-06-24T11:09:59.255705Z","steps":["trace[465503122] 'agreement among raft nodes before linearized reading' (duration: 396.785086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:59.255791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:58.858814Z","time spent":"396.969661ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T11:09:59.255817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:58.272577Z","time spent":"983.160952ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:09:59.255869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.256871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-06-24T11:09:59.255962Z","caller":"traceutil/trace.go:171","msg":"trace[1897901376] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1382; }","duration":"970.400265ms","start":"2026-06-24T11:09:58.285542Z","end":"2026-06-24T11:09:59.255942Z","steps":["trace[1897901376] 'agreement among raft nodes before linearized reading' (duration: 970.086378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:59.256008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:58.285531Z","time spent":"970.469086ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-24T11:09:59.256529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.606053ms","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-06-24T11:09:59.256617Z","caller":"traceutil/trace.go:171","msg":"trace[1956782458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1382; }","duration":"547.764577ms","start":"2026-06-24T11:09:58.708831Z","end":"2026-06-24T11:09:59.256595Z","steps":["trace[1956782458] 'agreement among raft nodes before linearized reading' (duration: 546.807655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:09:59.256692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:09:58.708811Z","time spent":"547.864139ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-24T11:11:50.233364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.421909ms","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-06-24T11:11:50.233472Z","caller":"traceutil/trace.go:171","msg":"trace[1696735767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1892; }","duration":"323.540542ms","start":"2026-06-24T11:11:49.90991Z","end":"2026-06-24T11:11:50.233451Z","steps":["trace[1696735767] 'range keys from in-memory index tree' (duration: 323.186305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:11:50.233524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:11:49.90984Z","time spent":"323.672356ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T11:11:50.233972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.011151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-24T11:11:50.233976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.54938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-24T11:11:50.234053Z","caller":"traceutil/trace.go:171","msg":"trace[2020487671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1892; }","duration":"307.138954ms","start":"2026-06-24T11:11:49.926897Z","end":"2026-06-24T11:11:50.234036Z","steps":["trace[2020487671] 'range keys from in-memory index tree' (duration: 306.858648ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:11:50.234078Z","caller":"traceutil/trace.go:171","msg":"trace[278046445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1892; }","duration":"309.703154ms","start":"2026-06-24T11:11:49.924349Z","end":"2026-06-24T11:11:50.234052Z","steps":["trace[278046445] 'range keys from in-memory index tree' (duration: 309.341036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:11:50.234094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:11:49.92688Z","time spent":"307.205125ms","remote":"127.0.0.1:50872","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-06-24T11:11:50.234139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:11:49.924329Z","time spent":"309.799965ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-24T11:12:14.036774Z","caller":"traceutil/trace.go:171","msg":"trace[415106103] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"102.754623ms","start":"2026-06-24T11:12:13.933971Z","end":"2026-06-24T11:12:14.036726Z","steps":["trace[415106103] 'process raft request' (duration: 41.630672ms)","trace[415106103] 'compare' (duration: 60.795653ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:13:47.245367Z","caller":"traceutil/trace.go:171","msg":"trace[1234707610] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"399.765146ms","start":"2026-06-24T11:13:46.845576Z","end":"2026-06-24T11:13:47.245341Z","steps":["trace[1234707610] 'process raft request' (duration: 399.601062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:47.245474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.1162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-24T11:13:47.245487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:46.845563Z","time spent":"399.867398ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:13:47.245514Z","caller":"traceutil/trace.go:171","msg":"trace[87636966] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2451; }","duration":"399.207951ms","start":"2026-06-24T11:13:46.846298Z","end":"2026-06-24T11:13:47.245506Z","steps":["trace[87636966] 'agreement among raft nodes before linearized reading' (duration: 399.116249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:47.245543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:46.846283Z","time spent":"399.252753ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":13,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-06-24T11:13:47.245373Z","caller":"traceutil/trace.go:171","msg":"trace[1989879400] linearizableReadLoop","detail":"{readStateIndex:2555; appliedIndex:2554; }","duration":"398.974616ms","start":"2026-06-24T11:13:46.846344Z","end":"2026-06-24T11:13:47.245318Z","steps":["trace[1989879400] 'read index received' (duration: 398.827003ms)","trace[1989879400] 'applied index is now lower than readState.Index' (duration: 146.693µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:13:47.25328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.289554ms","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-06-24T11:13:47.253293Z","caller":"traceutil/trace.go:171","msg":"trace[2083809431] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"406.161683ms","start":"2026-06-24T11:13:46.847115Z","end":"2026-06-24T11:13:47.253276Z","steps":["trace[2083809431] 'process raft request' (duration: 405.867817ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:13:47.253341Z","caller":"traceutil/trace.go:171","msg":"trace[1157557204] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2453; }","duration":"405.408036ms","start":"2026-06-24T11:13:46.847915Z","end":"2026-06-24T11:13:47.253323Z","steps":["trace[1157557204] 'agreement among raft nodes before linearized reading' (duration: 405.216452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:47.253346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.540632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T11:13:47.253379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:46.847098Z","time spent":"406.238265ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:13:47.253386Z","caller":"traceutil/trace.go:171","msg":"trace[1414218512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2453; }","duration":"396.601203ms","start":"2026-06-24T11:13:46.856775Z","end":"2026-06-24T11:13:47.253376Z","steps":["trace[1414218512] 'agreement among raft nodes before linearized reading' (duration: 396.555952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:47.253387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:46.847895Z","time spent":"405.480208ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-24T11:13:47.253407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:46.856761Z","time spent":"396.640854ms","remote":"127.0.0.1:50588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T11:13:47.253289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.876365ms","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-06-24T11:13:47.253458Z","caller":"traceutil/trace.go:171","msg":"trace[329556211] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2453; }","duration":"196.09502ms","start":"2026-06-24T11:13:47.057353Z","end":"2026-06-24T11:13:47.253448Z","steps":["trace[329556211] 'agreement among raft nodes before linearized reading' (duration: 195.809184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:52.606684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.205306ms","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-06-24T11:13:52.606793Z","caller":"traceutil/trace.go:171","msg":"trace[1578152786] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2478; }","duration":"297.353639ms","start":"2026-06-24T11:13:52.309413Z","end":"2026-06-24T11:13:52.606767Z","steps":["trace[1578152786] 'range keys from in-memory index tree' (duration: 296.971031ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:13:53.311569Z","caller":"traceutil/trace.go:171","msg":"trace[1163546723] linearizableReadLoop","detail":"{readStateIndex:2586; appliedIndex:2584; }","duration":"454.845167ms","start":"2026-06-24T11:13:52.856705Z","end":"2026-06-24T11:13:53.31155Z","steps":["trace[1163546723] 'read index received' (duration: 454.773586ms)","trace[1163546723] 'applied index is now lower than readState.Index' (duration: 70.601µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:13:53.311761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.040462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2581"} {"level":"info","ts":"2026-06-24T11:13:53.311746Z","caller":"traceutil/trace.go:171","msg":"trace[143659045] transaction","detail":"{read_only:false; response_revision:2481; number_of_response:1; }","duration":"699.208893ms","start":"2026-06-24T11:13:52.612463Z","end":"2026-06-24T11:13:53.311671Z","steps":["trace[143659045] 'process raft request' (duration: 699.056269ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:13:53.311797Z","caller":"traceutil/trace.go:171","msg":"trace[248060184] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2481; }","duration":"455.134584ms","start":"2026-06-24T11:13:52.856653Z","end":"2026-06-24T11:13:53.311788Z","steps":["trace[248060184] 'agreement among raft nodes before linearized reading' (duration: 454.99023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:53.311834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:52.856638Z","time spent":"455.191406ms","remote":"127.0.0.1:50780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2605,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-06-24T11:13:53.311695Z","caller":"traceutil/trace.go:171","msg":"trace[2900370] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"700.083052ms","start":"2026-06-24T11:13:52.611517Z","end":"2026-06-24T11:13:53.3116Z","steps":["trace[2900370] 'process raft request' (duration: 699.891758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:53.311943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:52.612453Z","time spent":"699.383608ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:53.312119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:52.611506Z","time spent":"700.473132ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:53.812324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778051338780909436,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T11:13:54.312596Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778051338780909436,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T11:13:55.53969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083387629s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T11:13:55.540306Z","caller":"traceutil/trace.go:171","msg":"trace[2037427409] transaction","detail":"{read_only:false; response_revision:2484; number_of_response:1; }","duration":"1.159744333s","start":"2026-06-24T11:13:54.380547Z","end":"2026-06-24T11:13:55.540291Z","steps":["trace[2037427409] 'process raft request' (duration: 1.159693782s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:55.540372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:54.380513Z","time spent":"1.159826754s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:13:55.540468Z","caller":"traceutil/trace.go:171","msg":"trace[1897117563] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"2.254221497s","start":"2026-06-24T11:13:53.286217Z","end":"2026-06-24T11:13:55.540439Z","steps":["trace[1897117563] 'process raft request' (duration: 2.253575583s)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:13:55.540532Z","caller":"traceutil/trace.go:171","msg":"trace[1362847570] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"2.683681919s","start":"2026-06-24T11:13:52.856811Z","end":"2026-06-24T11:13:55.540493Z","steps":["trace[1362847570] 'process raft request' (duration: 1.59943242s)","trace[1362847570] 'compare' (duration: 1.08257925s)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:13:55.540599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.258400714s","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-06-24T11:13:55.540626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:53.286204Z","time spent":"2.25433938s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:13:55.540541Z","caller":"traceutil/trace.go:171","msg":"trace[884714732] linearizableReadLoop","detail":"{readStateIndex:2588; appliedIndex:2586; }","duration":"2.22881638s","start":"2026-06-24T11:13:53.311623Z","end":"2026-06-24T11:13:55.540439Z","steps":["trace[884714732] 'read index received' (duration: 1.144640033s)","trace[884714732] 'applied index is now lower than readState.Index' (duration: 1.084172187s)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:13:55.540656Z","caller":"traceutil/trace.go:171","msg":"trace[722374302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2484; }","duration":"2.258492086s","start":"2026-06-24T11:13:53.282148Z","end":"2026-06-24T11:13:55.540641Z","steps":["trace[722374302] 'agreement among raft nodes before linearized reading' (duration: 2.258344743s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:55.540691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:53.282129Z","time spent":"2.258556737s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T11:13:55.540746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.233484478s","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-06-24T11:13:55.540782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.249498878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-24T11:13:55.540779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.655407655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:13:55.540806Z","caller":"traceutil/trace.go:171","msg":"trace[1970412884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2484; }","duration":"2.23359009s","start":"2026-06-24T11:13:53.307198Z","end":"2026-06-24T11:13:55.540788Z","steps":["trace[1970412884] 'agreement among raft nodes before linearized reading' (duration: 2.233415796s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:55.540865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:53.307177Z","time spent":"2.233675472s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-24T11:13:55.541229Z","caller":"traceutil/trace.go:171","msg":"trace[41326523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2484; }","duration":"2.249546159s","start":"2026-06-24T11:13:53.291251Z","end":"2026-06-24T11:13:55.540797Z","steps":["trace[41326523] 'agreement among raft nodes before linearized reading' (duration: 2.249190061s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:55.541264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:53.291235Z","time spent":"2.25002167s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-24T11:13:55.541323Z","caller":"traceutil/trace.go:171","msg":"trace[540224266] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2484; }","duration":"2.655463727s","start":"2026-06-24T11:13:52.885338Z","end":"2026-06-24T11:13:55.540802Z","steps":["trace[540224266] 'agreement among raft nodes before linearized reading' (duration: 2.655404215s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:55.541395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:52.885325Z","time spent":"2.65606288s","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-06-24T11:13:55.540679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:52.856797Z","time spent":"2.683819241s","remote":"127.0.0.1:50662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T11:13:55.858626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999871731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-24T11:13:55.858697Z","caller":"traceutil/trace.go:171","msg":"trace[430302439] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000032404s","start":"2026-06-24T11:13:53.858646Z","end":"2026-06-24T11:13:55.858679Z","steps":["trace[430302439] 'agreement among raft nodes before linearized reading' (duration: 1.999914642s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:55.858733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:53.85863Z","time spent":"2.000095575s","remote":"127.0.0.1:50582","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-06-24T11:13:56.204893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.803579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T11:13:56.204988Z","caller":"traceutil/trace.go:171","msg":"trace[1733122067] linearizableReadLoop","detail":"{readStateIndex:2590; appliedIndex:2589; }","duration":"664.293156ms","start":"2026-06-24T11:13:55.540678Z","end":"2026-06-24T11:13:56.204972Z","steps":["trace[1733122067] 'read index received' (duration: 418.351924ms)","trace[1733122067] 'applied index is now lower than readState.Index' (duration: 245.940082ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:13:56.205171Z","caller":"traceutil/trace.go:171","msg":"trace[344156711] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"1.600638297s","start":"2026-06-24T11:13:54.604517Z","end":"2026-06-24T11:13:56.205156Z","steps":["trace[344156711] 'process raft request' (duration: 1.354498461s)","trace[344156711] 'compare' (duration: 245.693006ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:13:56.20523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:54.60448Z","time spent":"1.600717529s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:56.205513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.465683182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-24T11:13:56.205547Z","caller":"traceutil/trace.go:171","msg":"trace[1833551062] range","detail":"{range_begin:/registry/masterleases/199.19.213.140; range_end:; response_count:1; response_revision:2485; }","duration":"2.465746853s","start":"2026-06-24T11:13:53.739788Z","end":"2026-06-24T11:13:56.205534Z","steps":["trace[1833551062] 'agreement among raft nodes before linearized reading' (duration: 2.46563066s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:56.205578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:53.739767Z","time spent":"2.465804305s","remote":"127.0.0.1:50628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.140\" "} {"level":"warn","ts":"2026-06-24T11:13:56.205571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.488545ms","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-06-24T11:13:56.205617Z","caller":"traceutil/trace.go:171","msg":"trace[1153910349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2485; }","duration":"888.567357ms","start":"2026-06-24T11:13:55.317037Z","end":"2026-06-24T11:13:56.205604Z","steps":["trace[1153910349] 'agreement among raft nodes before linearized reading' (duration: 888.474005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:56.205614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.386178223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-06-24T11:13:56.205639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:55.317021Z","time spent":"888.614378ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-24T11:13:56.205671Z","caller":"traceutil/trace.go:171","msg":"trace[499611243] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2485; }","duration":"1.386260805s","start":"2026-06-24T11:13:54.819393Z","end":"2026-06-24T11:13:56.205653Z","steps":["trace[499611243] 'agreement among raft nodes before linearized reading' (duration: 1.386111821s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:56.205707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:54.819377Z","time spent":"1.386320977s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-24T11:13:56.205683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.889439182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-06-24T11:13:56.205727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39941114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-06-24T11:13:56.205765Z","caller":"traceutil/trace.go:171","msg":"trace[1511953153] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2485; }","duration":"2.889544974s","start":"2026-06-24T11:13:53.316196Z","end":"2026-06-24T11:13:56.205741Z","steps":["trace[1511953153] 'agreement among raft nodes before linearized reading' (duration: 2.889318348s)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:13:56.205789Z","caller":"traceutil/trace.go:171","msg":"trace[1585179554] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2485; }","duration":"1.399530392s","start":"2026-06-24T11:13:54.806242Z","end":"2026-06-24T11:13:56.205772Z","steps":["trace[1585179554] 'agreement among raft nodes before linearized reading' (duration: 1.399350077s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:56.205805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54485568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-24T11:13:56.205825Z","caller":"traceutil/trace.go:171","msg":"trace[489313189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2485; }","duration":"1.544915921s","start":"2026-06-24T11:13:54.660904Z","end":"2026-06-24T11:13:56.20582Z","steps":["trace[489313189] 'agreement among raft nodes before linearized reading' (duration: 1.544319067s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:56.205817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:53.316182Z","time spent":"2.889623117s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-24T11:13:56.205838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:54.660869Z","time spent":"1.544966983s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-24T11:13:56.205837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:54.806216Z","time spent":"1.399612134s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-24T11:13:57.0468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.41111ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:13:57.046924Z","caller":"traceutil/trace.go:171","msg":"trace[536983170] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2485; }","duration":"142.554853ms","start":"2026-06-24T11:13:56.904349Z","end":"2026-06-24T11:13:57.046904Z","steps":["trace[536983170] 'range keys from in-memory index tree' (duration: 142.39062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:57.047501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.171443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T11:13:57.047803Z","caller":"traceutil/trace.go:171","msg":"trace[408868890] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"1.502780077s","start":"2026-06-24T11:13:55.544989Z","end":"2026-06-24T11:13:57.047769Z","steps":["trace[408868890] 'process raft request' (duration: 819.258136ms)","trace[408868890] 'compare' (duration: 682.953178ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:13:57.047928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.501782494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:13:57.047976Z","caller":"traceutil/trace.go:171","msg":"trace[836941076] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2488; }","duration":"1.501862175s","start":"2026-06-24T11:13:55.546102Z","end":"2026-06-24T11:13:57.047964Z","steps":["trace[836941076] 'agreement among raft nodes before linearized reading' (duration: 1.501752573s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:57.048032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:55.546084Z","time spent":"1.501936008s","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-06-24T11:13:57.048036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:55.544968Z","time spent":"1.502911529s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:13:57.048101Z","caller":"traceutil/trace.go:171","msg":"trace[1210785364] transaction","detail":"{read_only:false; response_revision:2487; number_of_response:1; }","duration":"1.502824867s","start":"2026-06-24T11:13:55.545255Z","end":"2026-06-24T11:13:57.04808Z","steps":["trace[1210785364] 'process raft request' (duration: 1.502337296s)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:13:57.047823Z","caller":"traceutil/trace.go:171","msg":"trace[702363120] linearizableReadLoop","detail":"{readStateIndex:2593; appliedIndex:2590; }","duration":"842.79096ms","start":"2026-06-24T11:13:56.205013Z","end":"2026-06-24T11:13:57.047804Z","steps":["trace[702363120] 'read index received' (duration: 159.246779ms)","trace[702363120] 'applied index is now lower than readState.Index' (duration: 683.542681ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:13:57.048149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.363678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:13:57.048185Z","caller":"traceutil/trace.go:171","msg":"trace[1429837991] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"1.502263595s","start":"2026-06-24T11:13:55.545899Z","end":"2026-06-24T11:13:57.048163Z","steps":["trace[1429837991] 'process raft request' (duration: 1.501799854s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:57.048218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188757222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:13:57.048231Z","caller":"traceutil/trace.go:171","msg":"trace[588512539] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2488; }","duration":"993.507991ms","start":"2026-06-24T11:13:56.054706Z","end":"2026-06-24T11:13:57.048214Z","steps":["trace[588512539] 'agreement among raft nodes before linearized reading' (duration: 993.335877ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:13:57.048257Z","caller":"traceutil/trace.go:171","msg":"trace[1592605666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2488; }","duration":"1.188826214s","start":"2026-06-24T11:13:55.859419Z","end":"2026-06-24T11:13:57.048245Z","steps":["trace[1592605666] 'agreement among raft nodes before linearized reading' (duration: 1.188761842s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:57.048279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:56.054688Z","time spent":"993.581373ms","remote":"127.0.0.1:50840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-06-24T11:13:57.048282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:55.545894Z","time spent":"1.502335076s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:57.04834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115351617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:13:57.048375Z","caller":"traceutil/trace.go:171","msg":"trace[151022430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2488; }","duration":"1.115415318s","start":"2026-06-24T11:13:55.932952Z","end":"2026-06-24T11:13:57.048367Z","steps":["trace[151022430] 'agreement among raft nodes before linearized reading' (duration: 1.115370307s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:57.048289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238541452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T11:13:57.048407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:55.932933Z","time spent":"1.115466349s","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T11:13:57.04842Z","caller":"traceutil/trace.go:171","msg":"trace[1808025282] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2488; }","duration":"1.238698536s","start":"2026-06-24T11:13:55.809703Z","end":"2026-06-24T11:13:57.048402Z","steps":["trace[1808025282] 'agreement among raft nodes before linearized reading' (duration: 1.238543233s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:57.048424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.478637119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-06-24T11:13:57.048469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:55.809688Z","time spent":"1.238765147s","remote":"127.0.0.1:33434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-06-24T11:13:57.048477Z","caller":"traceutil/trace.go:171","msg":"trace[1896334143] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2488; }","duration":"1.47871152s","start":"2026-06-24T11:13:55.569746Z","end":"2026-06-24T11:13:57.048457Z","steps":["trace[1896334143] 'agreement among raft nodes before linearized reading' (duration: 1.478572046s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:57.048195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:55.545251Z","time spent":"1.50289872s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:57.048589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:55.569728Z","time spent":"1.478783763s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-24T11:13:57.048297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:55.8594Z","time spent":"1.188890295s","remote":"127.0.0.1:50588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T11:13:57.555694Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778051338780909451,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T11:13:58.056739Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778051338780909451,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T11:13:58.556955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778051338780909451,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T11:13:58.853898Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.489606825s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-24T11:13:58.854363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:56.206602Z","time spent":"2.647753217s","remote":"127.0.0.1:50628","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-24T11:13:58.854403Z","caller":"traceutil/trace.go:171","msg":"trace[1846534895] transaction","detail":"{read_only:false; response_revision:2493; number_of_response:1; }","duration":"2.643031648s","start":"2026-06-24T11:13:56.21135Z","end":"2026-06-24T11:13:58.854381Z","steps":["trace[1846534895] 'process raft request' (duration: 2.642992027s)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:13:58.854425Z","caller":"traceutil/trace.go:171","msg":"trace[1221595657] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"2.644316118s","start":"2026-06-24T11:13:56.210081Z","end":"2026-06-24T11:13:58.854397Z","steps":["trace[1221595657] 'process raft request' (duration: 2.644159405s)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:13:58.854474Z","caller":"traceutil/trace.go:171","msg":"trace[1487100496] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"2.644309358s","start":"2026-06-24T11:13:56.210138Z","end":"2026-06-24T11:13:58.854447Z","steps":["trace[1487100496] 'process raft request' (duration: 2.644136714s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:58.854534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:56.211341Z","time spent":"2.64312408s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:13:58.854524Z","caller":"traceutil/trace.go:171","msg":"trace[806792001] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"2.644443791s","start":"2026-06-24T11:13:56.210046Z","end":"2026-06-24T11:13:58.854489Z","steps":["trace[806792001] 'process raft request' (duration: 2.644095234s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:58.854561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:56.210064Z","time spent":"2.64441637s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:13:58.85456Z","caller":"traceutil/trace.go:171","msg":"trace[2046318799] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"2.644274907s","start":"2026-06-24T11:13:56.210251Z","end":"2026-06-24T11:13:58.854525Z","steps":["trace[2046318799] 'process raft request' (duration: 2.644062342s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:58.854599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:56.210134Z","time spent":"2.64439894s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:58.854686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:56.210026Z","time spent":"2.644575264s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:58.854713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:56.210242Z","time spent":"2.644376559s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:59.055963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000284479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-24T11:13:59.056027Z","caller":"traceutil/trace.go:171","msg":"trace[69932185] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000378122s","start":"2026-06-24T11:13:57.055637Z","end":"2026-06-24T11:13:59.056015Z","steps":["trace[69932185] 'agreement among raft nodes before linearized reading' (duration: 2.00030355s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.0561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:57.055628Z","time spent":"2.000463634s","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/24 11:13:59 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-24T11:13:59.160515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.196118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T11:13:59.16091Z","caller":"traceutil/trace.go:171","msg":"trace[616664139] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"2.108472659s","start":"2026-06-24T11:13:57.052385Z","end":"2026-06-24T11:13:59.160858Z","steps":["trace[616664139] 'process raft request' (duration: 1.861847351s)","trace[616664139] 'compare' (duration: 245.985283ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:13:59.161032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:57.052367Z","time spent":"2.108606601s","remote":"127.0.0.1:50662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T11:13:59.161042Z","caller":"traceutil/trace.go:171","msg":"trace[1145290086] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"1.6141542s","start":"2026-06-24T11:13:57.546864Z","end":"2026-06-24T11:13:59.161019Z","steps":["trace[1145290086] 'process raft request' (duration: 1.613857913s)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:13:59.161069Z","caller":"traceutil/trace.go:171","msg":"trace[1070558447] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"2.107885645s","start":"2026-06-24T11:13:57.053132Z","end":"2026-06-24T11:13:59.161018Z","steps":["trace[1070558447] 'process raft request' (duration: 2.107498697s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.161472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:57.053122Z","time spent":"2.108218514s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:59.161652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:57.54684Z","time spent":"1.614308523s","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:59.302294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.500859ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-24T11:13:59.302471Z","caller":"traceutil/trace.go:171","msg":"trace[1076020771] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2497; }","duration":"446.29951ms","start":"2026-06-24T11:13:58.856154Z","end":"2026-06-24T11:13:59.302454Z","steps":["trace[1076020771] 'process raft request' (duration: 446.241609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.302569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:58.85613Z","time spent":"446.379182ms","remote":"127.0.0.1:50628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":41,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:13:59.302848Z","caller":"traceutil/trace.go:171","msg":"trace[299945380] linearizableReadLoop","detail":"{readStateIndex:2602; appliedIndex:2593; }","duration":"2.247160643s","start":"2026-06-24T11:13:57.055662Z","end":"2026-06-24T11:13:59.302823Z","steps":["trace[299945380] 'read index received' (duration: 1.798384745s)","trace[299945380] 'applied index is now lower than readState.Index' (duration: 448.774228ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:13:59.303281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.031759547s","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":"warn","ts":"2026-06-24T11:13:59.30337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.395668ms","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-06-24T11:13:59.303402Z","caller":"traceutil/trace.go:171","msg":"trace[2104199971] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2497; }","duration":"2.031953551s","start":"2026-06-24T11:13:57.271416Z","end":"2026-06-24T11:13:59.30337Z","steps":["trace[2104199971] 'agreement among raft nodes before linearized reading' (duration: 2.031736006s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.30341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.18238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:13:59.303459Z","caller":"traceutil/trace.go:171","msg":"trace[604724465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2497; }","duration":"247.263702ms","start":"2026-06-24T11:13:59.056183Z","end":"2026-06-24T11:13:59.303447Z","steps":["trace[604724465] 'agreement among raft nodes before linearized reading' (duration: 247.18335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.303469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:57.271398Z","time spent":"2.032058394s","remote":"127.0.0.1:33410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-24T11:13:59.303494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.119978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-24T11:13:59.303524Z","caller":"traceutil/trace.go:171","msg":"trace[872430557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2497; }","duration":"250.17692ms","start":"2026-06-24T11:13:59.05334Z","end":"2026-06-24T11:13:59.303516Z","steps":["trace[872430557] 'agreement among raft nodes before linearized reading' (duration: 250.095438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.303646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.32824ms","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-06-24T11:13:59.303415Z","caller":"traceutil/trace.go:171","msg":"trace[1779799592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2497; }","duration":"251.495179ms","start":"2026-06-24T11:13:59.051907Z","end":"2026-06-24T11:13:59.303402Z","steps":["trace[1779799592] 'agreement among raft nodes before linearized reading' (duration: 251.314175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.303783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403605725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T11:13:59.303815Z","caller":"traceutil/trace.go:171","msg":"trace[1409889143] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2497; }","duration":"1.403680087s","start":"2026-06-24T11:13:57.900125Z","end":"2026-06-24T11:13:59.303805Z","steps":["trace[1409889143] 'agreement among raft nodes before linearized reading' (duration: 1.403619406s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.303836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:57.900102Z","time spent":"1.403727919s","remote":"127.0.0.1:51024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-24T11:13:59.304322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.977866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T11:13:59.30437Z","caller":"traceutil/trace.go:171","msg":"trace[1038524057] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2497; }","duration":"808.071739ms","start":"2026-06-24T11:13:58.496287Z","end":"2026-06-24T11:13:59.304359Z","steps":["trace[1038524057] 'agreement among raft nodes before linearized reading' (duration: 807.979637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.304404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:58.496239Z","time spent":"808.154661ms","remote":"127.0.0.1:50676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":11,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-06-24T11:13:59.304416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.34168ms","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-06-24T11:13:59.304476Z","caller":"traceutil/trace.go:171","msg":"trace[190225760] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2497; }","duration":"987.450162ms","start":"2026-06-24T11:13:58.31701Z","end":"2026-06-24T11:13:59.30446Z","steps":["trace[190225760] 'agreement among raft nodes before linearized reading' (duration: 987.267438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.304517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:58.31699Z","time spent":"987.521593ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-24T11:13:59.303817Z","caller":"traceutil/trace.go:171","msg":"trace[1778310003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2497; }","duration":"249.469903ms","start":"2026-06-24T11:13:59.054278Z","end":"2026-06-24T11:13:59.303748Z","steps":["trace[1778310003] 'agreement among raft nodes before linearized reading' (duration: 248.973222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.838794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.556852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T11:13:59.839043Z","caller":"traceutil/trace.go:171","msg":"trace[1579154739] transaction","detail":"{read_only:false; response_revision:2502; number_of_response:1; }","duration":"502.886577ms","start":"2026-06-24T11:13:59.336143Z","end":"2026-06-24T11:13:59.83903Z","steps":["trace[1579154739] 'process raft request' (duration: 502.859156ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:13:59.839051Z","caller":"traceutil/trace.go:171","msg":"trace[602703042] transaction","detail":"{read_only:false; response_revision:2500; number_of_response:1; }","duration":"523.216497ms","start":"2026-06-24T11:13:59.315812Z","end":"2026-06-24T11:13:59.839028Z","steps":["trace[602703042] 'process raft request' (duration: 523.101974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.839093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:59.336125Z","time spent":"502.94417ms","remote":"127.0.0.1:50628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:59.839155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:59.315803Z","time spent":"523.299539ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:13:59.839155Z","caller":"traceutil/trace.go:171","msg":"trace[323256053] transaction","detail":"{read_only:false; response_revision:2498; number_of_response:1; }","duration":"531.54858ms","start":"2026-06-24T11:13:59.307577Z","end":"2026-06-24T11:13:59.839126Z","steps":["trace[323256053] 'process raft request' (duration: 98.597148ms)","trace[323256053] 'compare' (duration: 432.405619ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T11:13:59.839203Z","caller":"traceutil/trace.go:171","msg":"trace[635375175] transaction","detail":"{read_only:false; response_revision:2501; number_of_response:1; }","duration":"515.473859ms","start":"2026-06-24T11:13:59.323689Z","end":"2026-06-24T11:13:59.839163Z","steps":["trace[635375175] 'process raft request' (duration: 515.282233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.839278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:59.307549Z","time spent":"531.662303ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T11:13:59.839296Z","caller":"traceutil/trace.go:171","msg":"trace[637983949] transaction","detail":"{read_only:false; response_revision:2499; number_of_response:1; }","duration":"524.58457ms","start":"2026-06-24T11:13:59.314704Z","end":"2026-06-24T11:13:59.839288Z","steps":["trace[637983949] 'process raft request' (duration: 524.162069ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:13:59.839317Z","caller":"traceutil/trace.go:171","msg":"trace[547392708] linearizableReadLoop","detail":"{readStateIndex:2606; appliedIndex:2604; }","duration":"526.181426ms","start":"2026-06-24T11:13:59.313125Z","end":"2026-06-24T11:13:59.839307Z","steps":["trace[547392708] 'read index received' (duration: 21.858415ms)","trace[547392708] 'applied index is now lower than readState.Index' (duration: 504.322141ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:13:59.839333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:59.314687Z","time spent":"524.62693ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:59.839361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:59.323668Z","time spent":"515.610081ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:13:59.839485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.3598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18bc0013adb10a5f\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-06-24T11:13:59.83951Z","caller":"traceutil/trace.go:171","msg":"trace[54154696] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18bc0013adb10a5f; range_end:; response_count:1; response_revision:2502; }","duration":"526.421231ms","start":"2026-06-24T11:13:59.31308Z","end":"2026-06-24T11:13:59.839501Z","steps":["trace[54154696] 'agreement among raft nodes before linearized reading' (duration: 526.332129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.83953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:59.313059Z","time spent":"526.466812ms","remote":"127.0.0.1:50662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18bc0013adb10a5f\" "} {"level":"warn","ts":"2026-06-24T11:13:59.839591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.853423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T11:13:59.839628Z","caller":"traceutil/trace.go:171","msg":"trace[1037221446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2502; }","duration":"517.889094ms","start":"2026-06-24T11:13:59.321728Z","end":"2026-06-24T11:13:59.839617Z","steps":["trace[1037221446] 'agreement among raft nodes before linearized reading' (duration: 517.835423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T11:13:59.839655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:59.321676Z","time spent":"517.973496ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T11:13:59.839838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.840944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T11:13:59.839874Z","caller":"traceutil/trace.go:171","msg":"trace[389223950] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2502; }","duration":"211.924676ms","start":"2026-06-24T11:13:59.62794Z","end":"2026-06-24T11:13:59.839865Z","steps":["trace[389223950] 'agreement among raft nodes before linearized reading' (duration: 211.826383ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:14:00.18119Z","caller":"traceutil/trace.go:171","msg":"trace[1298289245] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"339.75498ms","start":"2026-06-24T11:13:59.841389Z","end":"2026-06-24T11:14:00.181144Z","steps":["trace[1298289245] 'process raft request' (duration: 297.935204ms)","trace[1298289245] 'compare' (duration: 41.638232ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:14:00.181364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:59.84132Z","time spent":"339.963884ms","remote":"127.0.0.1:50662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T11:14:00.181626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.894969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-24T11:14:00.181785Z","caller":"traceutil/trace.go:171","msg":"trace[786390058] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2503; }","duration":"339.115234ms","start":"2026-06-24T11:13:59.842633Z","end":"2026-06-24T11:14:00.181748Z","steps":["trace[786390058] 'agreement among raft nodes before linearized reading' (duration: 338.577752ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T11:14:00.181156Z","caller":"traceutil/trace.go:171","msg":"trace[185033043] linearizableReadLoop","detail":"{readStateIndex:2611; appliedIndex:2610; }","duration":"338.410478ms","start":"2026-06-24T11:13:59.842677Z","end":"2026-06-24T11:14:00.181087Z","steps":["trace[185033043] 'read index received' (duration: 296.823107ms)","trace[185033043] 'applied index is now lower than readState.Index' (duration: 41.586321ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T11:14:00.1821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T11:13:59.842612Z","time spent":"339.444272ms","remote":"127.0.0.1:50758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "}