{"level":"warn","ts":"2026-05-19T17:56:32.622839Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T17:56:32.622968Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.11: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.11:2380","--initial-cluster=instance=https://199.19.213.11:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.11:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.11:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-19T17:56:32.623066Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T17:56:32.623083Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-05-19T17:56:32.623113Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T17:56:32.625735Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"]} {"level":"info","ts":"2026-05-19T17:56:32.625917Z","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.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.11:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-19T17:56:32.630209Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.986254ms"} {"level":"info","ts":"2026-05-19T17:56:32.636935Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ce8ad90e35537ee3","cluster-id":"3c17b62c6529d987"} {"level":"info","ts":"2026-05-19T17:56:32.637017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T17:56:32.637042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 0"} {"level":"info","ts":"2026-05-19T17:56:32.637058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ce8ad90e35537ee3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T17:56:32.637068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 1"} {"level":"info","ts":"2026-05-19T17:56:32.637114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"warn","ts":"2026-05-19T17:56:32.652939Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T17:56:32.655084Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T17:56:32.656361Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-19T17:56:32.65871Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ce8ad90e35537ee3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T17:56:32.659025Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ce8ad90e35537ee3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T17:56:32.659127Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T17:56:32.659163Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T17:56:32.659177Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T17:56:32.662456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"info","ts":"2026-05-19T17:56:32.663167Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","added-peer-id":"ce8ad90e35537ee3","added-peer-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-05-19T17:56:32.66772Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T17:56:32.668439Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ce8ad90e35537ee3","initial-advertise-peer-urls":["https://199.19.213.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T17:56:32.668539Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T17:56:32.669359Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-05-19T17:56:32.66952Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-05-19T17:56:32.938596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T17:56:32.938655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T17:56:32.938673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgPreVoteResp from ce8ad90e35537ee3 at term 1"} {"level":"info","ts":"2026-05-19T17:56:32.938688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became candidate at term 2"} {"level":"info","ts":"2026-05-19T17:56:32.938695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgVoteResp from ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-05-19T17:56:32.938721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became leader at term 2"} {"level":"info","ts":"2026-05-19T17:56:32.938735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ce8ad90e35537ee3 elected leader ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-05-19T17:56:32.939456Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:56:32.940071Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ce8ad90e35537ee3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.11:2379]}","request-path":"/0/members/ce8ad90e35537ee3/attributes","cluster-id":"3c17b62c6529d987","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T17:56:32.940259Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T17:56:32.9404Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:56:32.940461Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:56:32.940484Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:56:32.940503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T17:56:32.942791Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T17:56:32.944461Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T17:56:32.944488Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T17:56:32.946023Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.11:2379"} {"level":"warn","ts":"2026-05-19T17:56:47.310023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.350874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:56:47.310147Z","caller":"traceutil/trace.go:171","msg":"trace[1389187478] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"1.018644102s","start":"2026-05-19T17:56:46.291487Z","end":"2026-05-19T17:56:47.310132Z","steps":["trace[1389187478] 'read index received' (duration: 42.388482ms)","trace[1389187478] 'applied index is now lower than readState.Index' (duration: 976.25443ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:56:47.310244Z","caller":"traceutil/trace.go:171","msg":"trace[2115215487] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"1.076190693s","start":"2026-05-19T17:56:46.233997Z","end":"2026-05-19T17:56:47.310187Z","steps":["trace[2115215487] 'process raft request' (duration: 99.898221ms)","trace[2115215487] 'compare' (duration: 975.18843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:56:47.310426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01893961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-05-19T17:56:47.310488Z","caller":"traceutil/trace.go:171","msg":"trace[634264354] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:406; }","duration":"1.019004082s","start":"2026-05-19T17:56:46.291469Z","end":"2026-05-19T17:56:47.310473Z","steps":["trace[634264354] 'agreement among raft nodes before linearized reading' (duration: 1.018817237s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:56:47.310476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:56:46.233978Z","time spent":"1.076397839s","remote":"127.0.0.1:58890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:56:47.310537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:56:46.291343Z","time spent":"1.019184687s","remote":"127.0.0.1:59062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6984,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-05-19T17:56:47.310888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.07447ms","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-05-19T17:56:47.310887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.855608ms","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-05-19T17:56:47.310933Z","caller":"traceutil/trace.go:171","msg":"trace[393198768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:406; }","duration":"402.152202ms","start":"2026-05-19T17:56:46.908769Z","end":"2026-05-19T17:56:47.310921Z","steps":["trace[393198768] 'agreement among raft nodes before linearized reading' (duration: 402.048399ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:56:47.310941Z","caller":"traceutil/trace.go:171","msg":"trace[1409270778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"726.945321ms","start":"2026-05-19T17:56:46.583983Z","end":"2026-05-19T17:56:47.310929Z","steps":["trace[1409270778] 'agreement among raft nodes before linearized reading' (duration: 726.836988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:56:47.310968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:56:46.90875Z","time spent":"402.211034ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T17:56:47.310973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:56:46.583964Z","time spent":"727.001462ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:56:47.311305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.388761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T17:56:47.311365Z","caller":"traceutil/trace.go:171","msg":"trace[1020010908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:406; }","duration":"589.666458ms","start":"2026-05-19T17:56:46.721682Z","end":"2026-05-19T17:56:47.311348Z","steps":["trace[1020010908] 'agreement among raft nodes before linearized reading' (duration: 589.147134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:56:47.311409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:56:46.721658Z","time spent":"589.739651ms","remote":"127.0.0.1:59146","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-05-19T17:56:47.816958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144044418,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:56:48.317669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144044418,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:56:48.426525Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.110593298s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-19T17:56:49.505629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343116671s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:56:49.505828Z","caller":"traceutil/trace.go:171","msg":"trace[1975453316] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:406; }","duration":"1.343346848s","start":"2026-05-19T17:56:48.162452Z","end":"2026-05-19T17:56:49.505799Z","steps":["trace[1975453316] 'range keys from in-memory index tree' (duration: 1.34308583s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:56:49.506432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079378104s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:56:49.506521Z","caller":"traceutil/trace.go:171","msg":"trace[536917271] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"2.190669451s","start":"2026-05-19T17:56:47.315835Z","end":"2026-05-19T17:56:49.506505Z","steps":["trace[536917271] 'process raft request' (duration: 1.111086121s)","trace[536917271] 'compare' (duration: 1.078908181s)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:56:49.506591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:56:47.315816Z","time spent":"2.190740594s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:56:49.54439Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.117592496s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:56:49.54499Z","caller":"traceutil/trace.go:171","msg":"trace[586340062] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"2.228771531s","start":"2026-05-19T17:56:47.316175Z","end":"2026-05-19T17:56:49.544947Z","steps":["trace[586340062] 'process raft request' (duration: 2.228610696s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:56:49.545165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:56:47.316163Z","time spent":"2.228884735s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:56:49.546065Z","caller":"traceutil/trace.go:171","msg":"trace[628522353] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"2.229201994s","start":"2026-05-19T17:56:47.315898Z","end":"2026-05-19T17:56:49.5451Z","steps":["trace[628522353] 'process raft request' (duration: 2.228716269s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:56:49.546268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:56:47.315881Z","time spent":"2.230281514s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:56:49.546054Z","caller":"traceutil/trace.go:171","msg":"trace[964180782] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"2.226726834s","start":"2026-05-19T17:56:47.319264Z","end":"2026-05-19T17:56:49.545991Z","steps":["trace[964180782] 'process raft request' (duration: 2.225611551s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:56:49.546935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:56:47.319256Z","time spent":"2.227255938s","remote":"127.0.0.1:59062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:56:49.550452Z","caller":"traceutil/trace.go:171","msg":"trace[1534534179] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:418; }","duration":"2.234310758s","start":"2026-05-19T17:56:47.316103Z","end":"2026-05-19T17:56:49.550414Z","steps":["trace[1534534179] 'read index received' (duration: 1.110843875s)","trace[1534534179] 'applied index is now lower than readState.Index' (duration: 1.123465843s)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:56:49.55067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.234558966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T17:56:49.550715Z","caller":"traceutil/trace.go:171","msg":"trace[1018794971] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:411; }","duration":"2.234625827s","start":"2026-05-19T17:56:47.316073Z","end":"2026-05-19T17:56:49.550699Z","steps":["trace[1018794971] 'agreement among raft nodes before linearized reading' (duration: 2.234484753s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:56:49.550728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.844730693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T17:56:49.550792Z","caller":"traceutil/trace.go:171","msg":"trace[55150524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"1.844848117s","start":"2026-05-19T17:56:47.705928Z","end":"2026-05-19T17:56:49.550776Z","steps":["trace[55150524] 'agreement among raft nodes before linearized reading' (duration: 1.844702042s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:56:49.550843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:56:47.70591Z","time spent":"1.844915799s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T17:56:49.550743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:56:47.316064Z","time spent":"2.234670169s","remote":"127.0.0.1:58890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-05-19T17:56:49.551132Z","caller":"traceutil/trace.go:171","msg":"trace[631655160] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.227913465s","start":"2026-05-19T17:56:48.322635Z","end":"2026-05-19T17:56:49.550548Z","steps":["trace[631655160] 'process raft request' (duration: 1.227659547s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:56:49.551266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:56:48.32261Z","time spent":"1.228587144s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:56:49.662612Z","caller":"traceutil/trace.go:171","msg":"trace[1993114164] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"102.217538ms","start":"2026-05-19T17:56:49.560368Z","end":"2026-05-19T17:56:49.662586Z","steps":["trace[1993114164] 'process raft request' (duration: 102.027502ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:56:55.352618Z","caller":"traceutil/trace.go:171","msg":"trace[1307601635] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"171.978862ms","start":"2026-05-19T17:56:55.180615Z","end":"2026-05-19T17:56:55.352594Z","steps":["trace[1307601635] 'process raft request' (duration: 171.817057ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:32.287061Z","caller":"traceutil/trace.go:171","msg":"trace[1324425943] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"365.377177ms","start":"2026-05-19T17:57:31.921656Z","end":"2026-05-19T17:57:32.287033Z","steps":["trace[1324425943] 'read index received' (duration: 365.101669ms)","trace[1324425943] 'applied index is now lower than readState.Index' (duration: 274.448µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:57:32.287172Z","caller":"traceutil/trace.go:171","msg":"trace[1938557698] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"404.940196ms","start":"2026-05-19T17:57:31.882196Z","end":"2026-05-19T17:57:32.287136Z","steps":["trace[1938557698] 'process raft request' (duration: 404.645008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:32.287328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:31.882157Z","time spent":"405.094252ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:57:32.287374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.788972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:32.287427Z","caller":"traceutil/trace.go:171","msg":"trace[286984294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"263.885125ms","start":"2026-05-19T17:57:32.023528Z","end":"2026-05-19T17:57:32.287413Z","steps":["trace[286984294] 'agreement among raft nodes before linearized reading' (duration: 263.783462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:32.287463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.807218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-19T17:57:32.287478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.467499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-05-19T17:57:32.287516Z","caller":"traceutil/trace.go:171","msg":"trace[877948009] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:706; }","duration":"365.551511ms","start":"2026-05-19T17:57:31.921951Z","end":"2026-05-19T17:57:32.287502Z","steps":["trace[877948009] 'agreement among raft nodes before linearized reading' (duration: 365.306605ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:32.287514Z","caller":"traceutil/trace.go:171","msg":"trace[1205577973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:706; }","duration":"365.902541ms","start":"2026-05-19T17:57:31.921597Z","end":"2026-05-19T17:57:32.287499Z","steps":["trace[1205577973] 'agreement among raft nodes before linearized reading' (duration: 365.651515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:32.287543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:31.92194Z","time spent":"365.597392ms","remote":"127.0.0.1:58912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-19T17:57:32.287547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:31.921559Z","time spent":"365.980464ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T17:57:39.934325Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144045097,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T17:57:40.150208Z","caller":"traceutil/trace.go:171","msg":"trace[643740218] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"760.754208ms","start":"2026-05-19T17:57:39.389417Z","end":"2026-05-19T17:57:40.150171Z","steps":["trace[643740218] 'process raft request' (duration: 760.558362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:40.150415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:39.3894Z","time spent":"760.930953ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:57:40.483605Z","caller":"traceutil/trace.go:171","msg":"trace[1790027419] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"932.344318ms","start":"2026-05-19T17:57:39.551238Z","end":"2026-05-19T17:57:40.483582Z","steps":["trace[1790027419] 'process raft request' (duration: 932.151203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:40.483744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:39.551214Z","time spent":"932.4545ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4612,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:57:40.484051Z","caller":"traceutil/trace.go:171","msg":"trace[636207459] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:767; }","duration":"1.050037339s","start":"2026-05-19T17:57:39.433498Z","end":"2026-05-19T17:57:40.483536Z","steps":["trace[636207459] 'read index received' (duration: 716.505827ms)","trace[636207459] 'applied index is now lower than readState.Index' (duration: 333.530572ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:57:40.484134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050622985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wgbq2\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-19T17:57:40.484247Z","caller":"traceutil/trace.go:171","msg":"trace[1566891298] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wgbq2; range_end:; response_count:1; response_revision:743; }","duration":"1.050767979s","start":"2026-05-19T17:57:39.433461Z","end":"2026-05-19T17:57:40.484229Z","steps":["trace[1566891298] 'agreement among raft nodes before linearized reading' (duration: 1.050507042s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:40.484298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:39.433443Z","time spent":"1.050842351s","remote":"127.0.0.1:59062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-wgbq2\" "} {"level":"warn","ts":"2026-05-19T17:57:40.488744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.115214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T17:57:40.488834Z","caller":"traceutil/trace.go:171","msg":"trace[969444085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:744; }","duration":"672.247608ms","start":"2026-05-19T17:57:39.816566Z","end":"2026-05-19T17:57:40.488814Z","steps":["trace[969444085] 'agreement among raft nodes before linearized reading' (duration: 672.041102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:40.4891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.741361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T17:57:40.48914Z","caller":"traceutil/trace.go:171","msg":"trace[1788191580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:744; }","duration":"141.818933ms","start":"2026-05-19T17:57:40.347305Z","end":"2026-05-19T17:57:40.489124Z","steps":["trace[1788191580] 'agreement among raft nodes before linearized reading' (duration: 141.726911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:40.489097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:39.816541Z","time spent":"672.543985ms","remote":"127.0.0.1:59146","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-05-19T17:57:40.489343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.149865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:40.489376Z","caller":"traceutil/trace.go:171","msg":"trace[1370594995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"472.224637ms","start":"2026-05-19T17:57:40.017144Z","end":"2026-05-19T17:57:40.489368Z","steps":["trace[1370594995] 'agreement among raft nodes before linearized reading' (duration: 472.176335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:40.489395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:40.017121Z","time spent":"472.269808ms","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T17:57:40.489396Z","caller":"traceutil/trace.go:171","msg":"trace[1886361575] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"297.997254ms","start":"2026-05-19T17:57:40.191378Z","end":"2026-05-19T17:57:40.489375Z","steps":["trace[1886361575] 'process raft request' (duration: 297.12498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:40.489479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.847761ms","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-05-19T17:57:40.489526Z","caller":"traceutil/trace.go:171","msg":"trace[715787760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:744; }","duration":"664.915113ms","start":"2026-05-19T17:57:39.8246Z","end":"2026-05-19T17:57:40.489515Z","steps":["trace[715787760] 'agreement among raft nodes before linearized reading' (duration: 664.774439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:40.489555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:39.824591Z","time spent":"664.956185ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:57:40.489638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.957047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T17:57:40.489673Z","caller":"traceutil/trace.go:171","msg":"trace[397573275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:744; }","duration":"673.031139ms","start":"2026-05-19T17:57:39.816631Z","end":"2026-05-19T17:57:40.489662Z","steps":["trace[397573275] 'agreement among raft nodes before linearized reading' (duration: 672.958377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:40.489693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:39.816604Z","time spent":"673.084491ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T17:57:41.266108Z","caller":"traceutil/trace.go:171","msg":"trace[707965491] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"771.27209ms","start":"2026-05-19T17:57:40.494811Z","end":"2026-05-19T17:57:41.266083Z","steps":["trace[707965491] 'process raft request' (duration: 765.211361ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:41.266225Z","caller":"traceutil/trace.go:171","msg":"trace[1653933130] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"771.338361ms","start":"2026-05-19T17:57:40.494876Z","end":"2026-05-19T17:57:41.266215Z","steps":["trace[1653933130] 'process raft request' (duration: 771.167397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:41.266284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:40.494867Z","time spent":"771.383762ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:57:41.266445Z","caller":"traceutil/trace.go:171","msg":"trace[1960522732] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"770.859688ms","start":"2026-05-19T17:57:40.495573Z","end":"2026-05-19T17:57:41.266433Z","steps":["trace[1960522732] 'process raft request' (duration: 770.608941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:41.266513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:40.495562Z","time spent":"770.91905ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:57:41.266264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:40.494796Z","time spent":"771.386062ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:57:41.328938Z","caller":"traceutil/trace.go:171","msg":"trace[416055166] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"828.320236ms","start":"2026-05-19T17:57:40.500589Z","end":"2026-05-19T17:57:41.328909Z","steps":["trace[416055166] 'process raft request' (duration: 828.035528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:41.329099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:40.500575Z","time spent":"828.43583ms","remote":"127.0.0.1:59062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:57:42.680736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144045160,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:57:43.181679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144045160,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:57:43.682775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144045160,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:57:44.12858Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.894528475s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:57:44.129056Z","caller":"traceutil/trace.go:171","msg":"trace[58309538] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"1.948962137s","start":"2026-05-19T17:57:42.180071Z","end":"2026-05-19T17:57:44.129033Z","steps":["trace[58309538] 'read index received' (duration: 1.94869095s)","trace[58309538] 'applied index is now lower than readState.Index' (duration: 270.447µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:57:44.12924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.949135712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.12926Z","caller":"traceutil/trace.go:171","msg":"trace[1815525265] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.970251659s","start":"2026-05-19T17:57:42.158957Z","end":"2026-05-19T17:57:44.129209Z","steps":["trace[1815525265] 'process raft request' (duration: 1.969859998s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.129867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.628527249s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T17:57:44.129912Z","caller":"traceutil/trace.go:171","msg":"trace[1681990193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"1.628585821s","start":"2026-05-19T17:57:42.501308Z","end":"2026-05-19T17:57:44.129894Z","steps":["trace[1681990193] 'agreement among raft nodes before linearized reading' (duration: 1.628441747s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.129973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.158939Z","time spent":"1.970953588s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:57:44.129992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.501291Z","time spent":"1.628690685s","remote":"127.0.0.1:59146","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-05-19T17:57:44.12982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.393959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T17:57:44.130282Z","caller":"traceutil/trace.go:171","msg":"trace[1505695541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"859.902653ms","start":"2026-05-19T17:57:43.270337Z","end":"2026-05-19T17:57:44.130239Z","steps":["trace[1505695541] 'agreement among raft nodes before linearized reading' (duration: 859.308146ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:44.129328Z","caller":"traceutil/trace.go:171","msg":"trace[1933161196] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:750; }","duration":"1.949277136s","start":"2026-05-19T17:57:42.180039Z","end":"2026-05-19T17:57:44.129317Z","steps":["trace[1933161196] 'agreement among raft nodes before linearized reading' (duration: 1.949092441s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.130451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.180016Z","time spent":"1.950418239s","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:57:44.12933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.94905145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.130659Z","caller":"traceutil/trace.go:171","msg":"trace[110302465] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:750; }","duration":"1.950438079s","start":"2026-05-19T17:57:42.180184Z","end":"2026-05-19T17:57:44.130623Z","steps":["trace[110302465] 'agreement among raft nodes before linearized reading' (duration: 1.949008429s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.130732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.180178Z","time spent":"1.950531771s","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:57:44.12939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.761730733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.130808Z","caller":"traceutil/trace.go:171","msg":"trace[2125770639] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:750; }","duration":"1.763187703s","start":"2026-05-19T17:57:42.367601Z","end":"2026-05-19T17:57:44.130789Z","steps":["trace[2125770639] 'agreement among raft nodes before linearized reading' (duration: 1.761763733s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.130876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.367582Z","time spent":"1.763273916s","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:57:44.129382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.711569278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.130979Z","caller":"traceutil/trace.go:171","msg":"trace[1492020880] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:750; }","duration":"1.713215125s","start":"2026-05-19T17:57:42.417735Z","end":"2026-05-19T17:57:44.13095Z","steps":["trace[1492020880] 'agreement among raft nodes before linearized reading' (duration: 1.711491507s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.131044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.41772Z","time spent":"1.713303707s","remote":"127.0.0.1:48860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:57:44.129423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.74189648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.131137Z","caller":"traceutil/trace.go:171","msg":"trace[511451839] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:750; }","duration":"1.74360553s","start":"2026-05-19T17:57:42.387494Z","end":"2026-05-19T17:57:44.1311Z","steps":["trace[511451839] 'agreement among raft nodes before linearized reading' (duration: 1.741882562s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.131193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.387488Z","time spent":"1.743689562s","remote":"127.0.0.1:48838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-19T17:57:44.129423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.761810715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.131474Z","caller":"traceutil/trace.go:171","msg":"trace[1979185046] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:750; }","duration":"1.763844462s","start":"2026-05-19T17:57:42.367581Z","end":"2026-05-19T17:57:44.131425Z","steps":["trace[1979185046] 'agreement among raft nodes before linearized reading' (duration: 1.761793245s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.131516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.367569Z","time spent":"1.763933694s","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:57:44.12943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742592191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.131863Z","caller":"traceutil/trace.go:171","msg":"trace[1361976567] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:750; }","duration":"1.74505983s","start":"2026-05-19T17:57:42.386789Z","end":"2026-05-19T17:57:44.131849Z","steps":["trace[1361976567] 'agreement among raft nodes before linearized reading' (duration: 1.742622392s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.131925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.386765Z","time spent":"1.745135722s","remote":"127.0.0.1:48838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:57:44.129469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.725863976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.132059Z","caller":"traceutil/trace.go:171","msg":"trace[1933700649] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:750; }","duration":"1.728474408s","start":"2026-05-19T17:57:42.403576Z","end":"2026-05-19T17:57:44.13205Z","steps":["trace[1933700649] 'agreement among raft nodes before linearized reading' (duration: 1.725872336s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.132099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.403559Z","time spent":"1.72852722s","remote":"127.0.0.1:48854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-19T17:57:44.129473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742684103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.132177Z","caller":"traceutil/trace.go:171","msg":"trace[1578364634] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:750; }","duration":"1.745385169s","start":"2026-05-19T17:57:42.386766Z","end":"2026-05-19T17:57:44.132151Z","steps":["trace[1578364634] 'agreement among raft nodes before linearized reading' (duration: 1.742689154s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.132258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.386759Z","time spent":"1.745470251s","remote":"127.0.0.1:48838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:57:44.129476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.778234071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.132362Z","caller":"traceutil/trace.go:171","msg":"trace[1951687333] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:750; }","duration":"1.781144871s","start":"2026-05-19T17:57:42.351207Z","end":"2026-05-19T17:57:44.132352Z","steps":["trace[1951687333] 'agreement among raft nodes before linearized reading' (duration: 1.778242221s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.132399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.351195Z","time spent":"1.781193993s","remote":"127.0.0.1:48820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-05-19T17:57:44.129508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.726603216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.132558Z","caller":"traceutil/trace.go:171","msg":"trace[1429350581] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:750; }","duration":"1.729686852s","start":"2026-05-19T17:57:42.402862Z","end":"2026-05-19T17:57:44.132549Z","steps":["trace[1429350581] 'agreement among raft nodes before linearized reading' (duration: 1.726624287s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.132592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.402856Z","time spent":"1.729728203s","remote":"127.0.0.1:48854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:57:44.129506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.76055111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.132687Z","caller":"traceutil/trace.go:171","msg":"trace[650168633] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:750; }","duration":"1.763753318s","start":"2026-05-19T17:57:42.368927Z","end":"2026-05-19T17:57:44.13268Z","steps":["trace[650168633] 'agreement among raft nodes before linearized reading' (duration: 1.76056229s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.132712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.368806Z","time spent":"1.763899822s","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-05-19T17:57:44.129541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.106941ms","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-05-19T17:57:44.132803Z","caller":"traceutil/trace.go:171","msg":"trace[351360572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"862.446894ms","start":"2026-05-19T17:57:43.27035Z","end":"2026-05-19T17:57:44.132797Z","steps":["trace[351360572] 'agreement among raft nodes before linearized reading' (duration: 859.095121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.132827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:43.270319Z","time spent":"862.501416ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:57:44.129548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.825891075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.132994Z","caller":"traceutil/trace.go:171","msg":"trace[2125685720] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:750; }","duration":"1.829347563s","start":"2026-05-19T17:57:42.303638Z","end":"2026-05-19T17:57:44.132986Z","steps":["trace[2125685720] 'agreement among raft nodes before linearized reading' (duration: 1.825894336s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.13303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.303634Z","time spent":"1.829383024s","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-19T17:57:44.129545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.779112836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.133119Z","caller":"traceutil/trace.go:171","msg":"trace[760681053] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:750; }","duration":"1.782725057s","start":"2026-05-19T17:57:42.350385Z","end":"2026-05-19T17:57:44.133111Z","steps":["trace[760681053] 'agreement among raft nodes before linearized reading' (duration: 1.779116906s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.133145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.350364Z","time spent":"1.782773579s","remote":"127.0.0.1:48820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:57:44.129589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.77926199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.13325Z","caller":"traceutil/trace.go:171","msg":"trace[1657355974] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:750; }","duration":"1.782943283s","start":"2026-05-19T17:57:42.3503Z","end":"2026-05-19T17:57:44.133243Z","steps":["trace[1657355974] 'agreement among raft nodes before linearized reading' (duration: 1.77926481s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.133305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.350288Z","time spent":"1.783004135s","remote":"127.0.0.1:48820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:57:44.129573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66414862s","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:5"} {"level":"info","ts":"2026-05-19T17:57:44.133406Z","caller":"traceutil/trace.go:171","msg":"trace[1123494843] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:750; }","duration":"1.668000448s","start":"2026-05-19T17:57:42.465398Z","end":"2026-05-19T17:57:44.133399Z","steps":["trace[1123494843] 'agreement among raft nodes before linearized reading' (duration: 1.664157361s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.133431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.465384Z","time spent":"1.668039639s","remote":"127.0.0.1:48878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:57:44.129649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114247993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.133564Z","caller":"traceutil/trace.go:171","msg":"trace[455032062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"1.118194193s","start":"2026-05-19T17:57:43.015356Z","end":"2026-05-19T17:57:44.13355Z","steps":["trace[455032062] 'agreement among raft nodes before linearized reading' (duration: 1.114265723s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.133595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:43.015331Z","time spent":"1.118257495s","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:57:44.129664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664358265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.133815Z","caller":"traceutil/trace.go:171","msg":"trace[568142302] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:750; }","duration":"1.668494072s","start":"2026-05-19T17:57:42.465291Z","end":"2026-05-19T17:57:44.133785Z","steps":["trace[568142302] 'agreement among raft nodes before linearized reading' (duration: 1.664349016s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.133889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.465289Z","time spent":"1.668580324s","remote":"127.0.0.1:48878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:57:44.129645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.82675295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:57:44.133391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:43.270313Z","time spent":"860.185141ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T17:57:44.13408Z","caller":"traceutil/trace.go:171","msg":"trace[794358622] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:750; }","duration":"1.831195094s","start":"2026-05-19T17:57:42.302849Z","end":"2026-05-19T17:57:44.134044Z","steps":["trace[794358622] 'agreement among raft nodes before linearized reading' (duration: 1.82675682s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.134331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.30283Z","time spent":"1.831472782s","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:57:44.12967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.82674495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.134598Z","caller":"traceutil/trace.go:171","msg":"trace[1528795657] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:750; }","duration":"1.831677907s","start":"2026-05-19T17:57:42.302897Z","end":"2026-05-19T17:57:44.134575Z","steps":["trace[1528795657] 'agreement among raft nodes before linearized reading' (duration: 1.826678828s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.134666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.302838Z","time spent":"1.831811321s","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T17:57:44.129717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710845728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:57:44.12972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.687443607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:57:44.129735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.647892968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:57:44.129739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.711935919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:57:44.129731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.647893408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:57:44.129765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.44355ms","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-05-19T17:57:44.12978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.64796084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:57:44.129783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.687411597s","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:5"} {"level":"warn","ts":"2026-05-19T17:57:44.130149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.727299646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:57:44.129692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664433818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.134773Z","caller":"traceutil/trace.go:171","msg":"trace[519720668] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:750; }","duration":"1.71592524s","start":"2026-05-19T17:57:42.418835Z","end":"2026-05-19T17:57:44.134761Z","steps":["trace[519720668] 'agreement among raft nodes before linearized reading' (duration: 1.710868959s)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:44.134907Z","caller":"traceutil/trace.go:171","msg":"trace[313068534] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:750; }","duration":"1.732103919s","start":"2026-05-19T17:57:42.402797Z","end":"2026-05-19T17:57:44.1349Z","steps":["trace[313068534] 'agreement among raft nodes before linearized reading' (duration: 1.72672704s)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:44.134937Z","caller":"traceutil/trace.go:171","msg":"trace[1034024593] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:750; }","duration":"1.669699054s","start":"2026-05-19T17:57:42.465225Z","end":"2026-05-19T17:57:44.134923Z","steps":["trace[1034024593] 'agreement among raft nodes before linearized reading' (duration: 1.664453778s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.134971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.402779Z","time spent":"1.732170391s","remote":"127.0.0.1:48854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-19T17:57:44.13481Z","caller":"traceutil/trace.go:171","msg":"trace[1800145788] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:750; }","duration":"1.71701083s","start":"2026-05-19T17:57:42.417785Z","end":"2026-05-19T17:57:44.134796Z","steps":["trace[1800145788] 'agreement among raft nodes before linearized reading' (duration: 1.711942309s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.135009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.465209Z","time spent":"1.669785467s","remote":"127.0.0.1:48864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-05-19T17:57:44.135072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.417776Z","time spent":"1.717271137s","remote":"127.0.0.1:48860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:57:44.134799Z","caller":"traceutil/trace.go:171","msg":"trace[474790067] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:750; }","duration":"1.652951489s","start":"2026-05-19T17:57:42.481823Z","end":"2026-05-19T17:57:44.134774Z","steps":["trace[474790067] 'agreement among raft nodes before linearized reading' (duration: 1.647891178s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.135156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.48182Z","time spent":"1.653326159s","remote":"127.0.0.1:48880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:57:44.134984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.418817Z","time spent":"1.716118994s","remote":"127.0.0.1:48860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"info","ts":"2026-05-19T17:57:44.134837Z","caller":"traceutil/trace.go:171","msg":"trace[95007035] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:750; }","duration":"1.653064532s","start":"2026-05-19T17:57:42.481765Z","end":"2026-05-19T17:57:44.134829Z","steps":["trace[95007035] 'agreement among raft nodes before linearized reading' (duration: 1.647796116s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.135274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.481746Z","time spent":"1.653503394s","remote":"127.0.0.1:48880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"info","ts":"2026-05-19T17:57:44.134862Z","caller":"traceutil/trace.go:171","msg":"trace[2119420196] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:750; }","duration":"1.653049681s","start":"2026-05-19T17:57:42.481806Z","end":"2026-05-19T17:57:44.134855Z","steps":["trace[2119420196] 'agreement among raft nodes before linearized reading' (duration: 1.647958589s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.135403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.481802Z","time spent":"1.653572985s","remote":"127.0.0.1:48880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"info","ts":"2026-05-19T17:57:44.134853Z","caller":"traceutil/trace.go:171","msg":"trace[1821281039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"864.558643ms","start":"2026-05-19T17:57:43.270282Z","end":"2026-05-19T17:57:44.134841Z","steps":["trace[1821281039] 'agreement among raft nodes before linearized reading' (duration: 859.387019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.13552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:43.27026Z","time spent":"865.247531ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T17:57:44.134884Z","caller":"traceutil/trace.go:171","msg":"trace[704664721] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:750; }","duration":"1.69254856s","start":"2026-05-19T17:57:42.442328Z","end":"2026-05-19T17:57:44.134877Z","steps":["trace[704664721] 'agreement among raft nodes before linearized reading' (duration: 1.687414127s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.135646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.442307Z","time spent":"1.693312371s","remote":"127.0.0.1:48864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:57:44.134817Z","caller":"traceutil/trace.go:171","msg":"trace[883324697] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:750; }","duration":"1.692518939s","start":"2026-05-19T17:57:42.442245Z","end":"2026-05-19T17:57:44.134764Z","steps":["trace[883324697] 'agreement among raft nodes before linearized reading' (duration: 1.687438217s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:44.135783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:42.442231Z","time spent":"1.693536507s","remote":"127.0.0.1:48864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-05-19T17:57:44.379944Z","caller":"traceutil/trace.go:171","msg":"trace[220749440] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"243.469898ms","start":"2026-05-19T17:57:44.136449Z","end":"2026-05-19T17:57:44.379919Z","steps":["trace[220749440] 'process raft request' (duration: 243.333565ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:44.395565Z","caller":"traceutil/trace.go:171","msg":"trace[1576700204] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:778; }","duration":"252.427427ms","start":"2026-05-19T17:57:44.14311Z","end":"2026-05-19T17:57:44.395538Z","steps":["trace[1576700204] 'read index received' (duration: 236.805973ms)","trace[1576700204] 'applied index is now lower than readState.Index' (duration: 15.620454ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:57:44.395692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.565401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:44.395669Z","caller":"traceutil/trace.go:171","msg":"trace[1351409291] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"255.368509ms","start":"2026-05-19T17:57:44.140257Z","end":"2026-05-19T17:57:44.395625Z","steps":["trace[1351409291] 'process raft request' (duration: 255.173684ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:44.395726Z","caller":"traceutil/trace.go:171","msg":"trace[1804262868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"252.643232ms","start":"2026-05-19T17:57:44.143072Z","end":"2026-05-19T17:57:44.395715Z","steps":["trace[1804262868] 'agreement among raft nodes before linearized reading' (duration: 252.56214ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:44.39572Z","caller":"traceutil/trace.go:171","msg":"trace[1180826910] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"255.039ms","start":"2026-05-19T17:57:44.140653Z","end":"2026-05-19T17:57:44.395692Z","steps":["trace[1180826910] 'process raft request' (duration: 254.822524ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:44.396332Z","caller":"traceutil/trace.go:171","msg":"trace[518513241] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"256.44737ms","start":"2026-05-19T17:57:44.139746Z","end":"2026-05-19T17:57:44.396193Z","steps":["trace[518513241] 'process raft request' (duration: 255.505063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:48.867131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.113256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:57:48.867218Z","caller":"traceutil/trace.go:171","msg":"trace[1587786161] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:786; }","duration":"112.275712ms","start":"2026-05-19T17:57:48.754923Z","end":"2026-05-19T17:57:48.867198Z","steps":["trace[1587786161] 'count revisions from in-memory index tree' (duration: 111.983702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:01.519048Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144045398,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:58:02.019753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144045398,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:58:02.389007Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.545831488s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:58:02.389557Z","caller":"traceutil/trace.go:171","msg":"trace[1287236628] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"1.546500708s","start":"2026-05-19T17:58:00.843025Z","end":"2026-05-19T17:58:02.389526Z","steps":["trace[1287236628] 'process raft request' (duration: 1.546288941s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:02.389754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:00.842998Z","time spent":"1.546659622s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:58:02.405414Z","caller":"traceutil/trace.go:171","msg":"trace[1400525562] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"837.790115ms","start":"2026-05-19T17:58:01.567602Z","end":"2026-05-19T17:58:02.405392Z","steps":["trace[1400525562] 'process raft request' (duration: 837.60574ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:58:02.405394Z","caller":"traceutil/trace.go:171","msg":"trace[1176204048] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:899; }","duration":"1.3873745s","start":"2026-05-19T17:58:01.017994Z","end":"2026-05-19T17:58:02.405369Z","steps":["trace[1176204048] 'read index received' (duration: 1.371413659s)","trace[1176204048] 'applied index is now lower than readState.Index' (duration: 15.959611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:58:02.405557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:01.567572Z","time spent":"837.891568ms","remote":"127.0.0.1:59108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:58:02.405815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.653631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:58:02.405862Z","caller":"traceutil/trace.go:171","msg":"trace[20750628] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:870; }","duration":"101.762944ms","start":"2026-05-19T17:58:02.304087Z","end":"2026-05-19T17:58:02.405849Z","steps":["trace[20750628] 'agreement among raft nodes before linearized reading' (duration: 101.666041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:02.405879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190158452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T17:58:02.405934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.396339ms","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-05-19T17:58:02.405959Z","caller":"traceutil/trace.go:171","msg":"trace[1252687768] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:870; }","duration":"1.190376908s","start":"2026-05-19T17:58:01.215557Z","end":"2026-05-19T17:58:02.405934Z","steps":["trace[1252687768] 'agreement among raft nodes before linearized reading' (duration: 1.190157752s)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:58:02.405976Z","caller":"traceutil/trace.go:171","msg":"trace[2093916439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:870; }","duration":"137.481311ms","start":"2026-05-19T17:58:02.268484Z","end":"2026-05-19T17:58:02.405965Z","steps":["trace[2093916439] 'agreement among raft nodes before linearized reading' (duration: 137.352057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:02.406012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:01.215517Z","time spent":"1.190484511s","remote":"127.0.0.1:59374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:58:02.405913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.667597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:58:02.406103Z","caller":"traceutil/trace.go:171","msg":"trace[283583188] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:870; }","duration":"668.911454ms","start":"2026-05-19T17:58:01.737169Z","end":"2026-05-19T17:58:02.40608Z","steps":["trace[283583188] 'agreement among raft nodes before linearized reading' (duration: 668.681498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:02.406157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:01.737146Z","time spent":"668.997706ms","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:58:02.407204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389207189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:58:02.407259Z","caller":"traceutil/trace.go:171","msg":"trace[1498146304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"1.389301852s","start":"2026-05-19T17:58:01.017941Z","end":"2026-05-19T17:58:02.407243Z","steps":["trace[1498146304] 'agreement among raft nodes before linearized reading' (duration: 1.387499203s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:02.407298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:01.017916Z","time spent":"1.389374434s","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T17:58:11.901706Z","caller":"traceutil/trace.go:171","msg":"trace[706341536] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"645.643284ms","start":"2026-05-19T17:58:11.256035Z","end":"2026-05-19T17:58:11.901679Z","steps":["trace[706341536] 'process raft request' (duration: 645.436488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:11.901882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:11.256011Z","time spent":"645.781388ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5316,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:58:17.587079Z","caller":"traceutil/trace.go:171","msg":"trace[1581353330] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"148.718346ms","start":"2026-05-19T17:58:17.438333Z","end":"2026-05-19T17:58:17.587052Z","steps":["trace[1581353330] 'process raft request' (duration: 148.540441ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:58:22.84112Z","caller":"traceutil/trace.go:171","msg":"trace[2009336540] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"256.293026ms","start":"2026-05-19T17:58:22.584803Z","end":"2026-05-19T17:58:22.841096Z","steps":["trace[2009336540] 'read index received' (duration: 198.79084ms)","trace[2009336540] 'applied index is now lower than readState.Index' (duration: 57.500826ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:58:22.841387Z","caller":"traceutil/trace.go:171","msg":"trace[649993082] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"267.762786ms","start":"2026-05-19T17:58:22.573572Z","end":"2026-05-19T17:58:22.841335Z","steps":["trace[649993082] 'process raft request' (duration: 209.960242ms)","trace[649993082] 'compare' (duration: 57.403623ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:58:22.841552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.507591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-19T17:58:22.841553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.145153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T17:58:22.841596Z","caller":"traceutil/trace.go:171","msg":"trace[428657918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1032; }","duration":"249.215125ms","start":"2026-05-19T17:58:22.592371Z","end":"2026-05-19T17:58:22.841586Z","steps":["trace[428657918] 'agreement among raft nodes before linearized reading' (duration: 248.977388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:22.84155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.958534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:58:22.841654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.685626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T17:58:22.841665Z","caller":"traceutil/trace.go:171","msg":"trace[580661009] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1032; }","duration":"162.159079ms","start":"2026-05-19T17:58:22.679483Z","end":"2026-05-19T17:58:22.841642Z","steps":["trace[580661009] 'agreement among raft nodes before linearized reading' (duration: 161.902102ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:58:22.841693Z","caller":"traceutil/trace.go:171","msg":"trace[534468801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1032; }","duration":"253.779678ms","start":"2026-05-19T17:58:22.587907Z","end":"2026-05-19T17:58:22.841687Z","steps":["trace[534468801] 'agreement among raft nodes before linearized reading' (duration: 253.408268ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:58:22.841609Z","caller":"traceutil/trace.go:171","msg":"trace[2020809571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1032; }","duration":"253.85412ms","start":"2026-05-19T17:58:22.587726Z","end":"2026-05-19T17:58:22.841581Z","steps":["trace[2020809571] 'agreement among raft nodes before linearized reading' (duration: 253.651534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:22.843954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.095922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T17:58:22.844242Z","caller":"traceutil/trace.go:171","msg":"trace[1531511083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1032; }","duration":"259.468852ms","start":"2026-05-19T17:58:22.584751Z","end":"2026-05-19T17:58:22.844219Z","steps":["trace[1531511083] 'agreement among raft nodes before linearized reading' (duration: 257.813498ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:58:23.134128Z","caller":"traceutil/trace.go:171","msg":"trace[1706925735] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"284.47463ms","start":"2026-05-19T17:58:22.849623Z","end":"2026-05-19T17:58:23.134097Z","steps":["trace[1706925735] 'process raft request' (duration: 284.255345ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:58:23.134462Z","caller":"traceutil/trace.go:171","msg":"trace[1081446048] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"282.436125ms","start":"2026-05-19T17:58:22.851981Z","end":"2026-05-19T17:58:23.134417Z","steps":["trace[1081446048] 'process raft request' (duration: 282.299961ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:58:23.134488Z","caller":"traceutil/trace.go:171","msg":"trace[1562723419] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"284.737568ms","start":"2026-05-19T17:58:22.849701Z","end":"2026-05-19T17:58:23.134438Z","steps":["trace[1562723419] 'process raft request' (duration: 284.334777ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:58:38.767442Z","caller":"traceutil/trace.go:171","msg":"trace[479561072] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"157.648566ms","start":"2026-05-19T17:58:38.609773Z","end":"2026-05-19T17:58:38.767422Z","steps":["trace[479561072] 'read index received' (duration: 157.374999ms)","trace[479561072] 'applied index is now lower than readState.Index' (duration: 272.677µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:58:38.767492Z","caller":"traceutil/trace.go:171","msg":"trace[1577334624] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"592.565717ms","start":"2026-05-19T17:58:38.174898Z","end":"2026-05-19T17:58:38.767464Z","steps":["trace[1577334624] 'process raft request' (duration: 592.351892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:38.767618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:38.174872Z","time spent":"592.68177ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:58:38.767619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.823952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T17:58:38.767653Z","caller":"traceutil/trace.go:171","msg":"trace[1226131274] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1088; }","duration":"157.911873ms","start":"2026-05-19T17:58:38.609732Z","end":"2026-05-19T17:58:38.767644Z","steps":["trace[1226131274] 'agreement among raft nodes before linearized reading' (duration: 157.78444ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:58:38.889691Z","caller":"traceutil/trace.go:171","msg":"trace[1334152266] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"117.86963ms","start":"2026-05-19T17:58:38.77178Z","end":"2026-05-19T17:58:38.88965Z","steps":["trace[1334152266] 'process raft request' (duration: 116.05564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:39.708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144045873,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:58:40.208429Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144045873,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:58:40.610939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.618134434s","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":"info","ts":"2026-05-19T17:58:40.611033Z","caller":"traceutil/trace.go:171","msg":"trace[1765381818] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1089; }","duration":"1.618298809s","start":"2026-05-19T17:58:38.992709Z","end":"2026-05-19T17:58:40.611008Z","steps":["trace[1765381818] 'count revisions from in-memory index tree' (duration: 1.618021402s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:40.61109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:38.992667Z","time spent":"1.618406842s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:58:40.611291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546556158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:58:40.611333Z","caller":"traceutil/trace.go:171","msg":"trace[994656704] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1089; }","duration":"1.54664518s","start":"2026-05-19T17:58:39.064678Z","end":"2026-05-19T17:58:40.611324Z","steps":["trace[994656704] 'count revisions from in-memory index tree' (duration: 1.546465135s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:40.611361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:39.064654Z","time spent":"1.546698862s","remote":"127.0.0.1:59342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:58:40.611469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586053687s","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-05-19T17:58:40.611537Z","caller":"traceutil/trace.go:171","msg":"trace[1645261877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"1.58614667s","start":"2026-05-19T17:58:39.025373Z","end":"2026-05-19T17:58:40.61152Z","steps":["trace[1645261877] 'range keys from in-memory index tree' (duration: 1.585930753s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:40.611568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:39.025367Z","time spent":"1.586194571s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T17:58:40.611541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.594825084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:58:40.611643Z","caller":"traceutil/trace.go:171","msg":"trace[1574743479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"1.594952819s","start":"2026-05-19T17:58:39.016661Z","end":"2026-05-19T17:58:40.611613Z","steps":["trace[1574743479] 'range keys from in-memory index tree' (duration: 1.594776623s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:40.6117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:39.016654Z","time spent":"1.5950317s","remote":"127.0.0.1:58878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:58:40.709035Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144045873,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:58:41.204552Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.543782573s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:58:41.205173Z","caller":"traceutil/trace.go:171","msg":"trace[2006409145] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"2.005646924s","start":"2026-05-19T17:58:39.199496Z","end":"2026-05-19T17:58:41.205142Z","steps":["trace[2006409145] 'process raft request' (duration: 2.005436268s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:41.205457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:39.199467Z","time spent":"2.005787277s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:58:41.209066Z","caller":"traceutil/trace.go:171","msg":"trace[600836608] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1130; }","duration":"2.001289217s","start":"2026-05-19T17:58:39.207736Z","end":"2026-05-19T17:58:41.209025Z","steps":["trace[600836608] 'read index received' (duration: 1.997143584s)","trace[600836608] 'applied index is now lower than readState.Index' (duration: 4.143972ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:58:41.209095Z","caller":"traceutil/trace.go:171","msg":"trace[1237136479] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"592.525988ms","start":"2026-05-19T17:58:40.616552Z","end":"2026-05-19T17:58:41.209078Z","steps":["trace[1237136479] 'process raft request' (duration: 592.323693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:41.209241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001489192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-19T17:58:41.209286Z","caller":"traceutil/trace.go:171","msg":"trace[1285846923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1091; }","duration":"2.001582744s","start":"2026-05-19T17:58:39.207696Z","end":"2026-05-19T17:58:41.209278Z","steps":["trace[1285846923] 'agreement among raft nodes before linearized reading' (duration: 2.00143306s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:41.209313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:39.207683Z","time spent":"2.001624245s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T17:58:41.20936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216036572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:58:41.209393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.717745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T17:58:41.209401Z","caller":"traceutil/trace.go:171","msg":"trace[1257265206] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1091; }","duration":"1.216138864s","start":"2026-05-19T17:58:39.993249Z","end":"2026-05-19T17:58:41.209388Z","steps":["trace[1257265206] 'agreement among raft nodes before linearized reading' (duration: 1.216062162s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:41.209378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.594128ms","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-05-19T17:58:41.209443Z","caller":"traceutil/trace.go:171","msg":"trace[1427345542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1091; }","duration":"315.808927ms","start":"2026-05-19T17:58:40.893619Z","end":"2026-05-19T17:58:41.209428Z","steps":["trace[1427345542] 'agreement among raft nodes before linearized reading' (duration: 315.622952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:41.209446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:39.993223Z","time spent":"1.216213667s","remote":"127.0.0.1:49080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:58:41.209461Z","caller":"traceutil/trace.go:171","msg":"trace[375348316] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1091; }","duration":"885.734843ms","start":"2026-05-19T17:58:40.323705Z","end":"2026-05-19T17:58:41.20944Z","steps":["trace[375348316] 'agreement among raft nodes before linearized reading' (duration: 885.525407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:41.209465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.928389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:58:41.209462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000115224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-19T17:58:41.209504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:40.323684Z","time spent":"885.808354ms","remote":"127.0.0.1:59228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:58:41.209545Z","caller":"traceutil/trace.go:171","msg":"trace[715550433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1091; }","duration":"589.045383ms","start":"2026-05-19T17:58:40.620478Z","end":"2026-05-19T17:58:41.209524Z","steps":["trace[715550433] 'agreement among raft nodes before linearized reading' (duration: 588.897949ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:58:41.209564Z","caller":"traceutil/trace.go:171","msg":"trace[1656377108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"2.000254137s","start":"2026-05-19T17:58:39.20929Z","end":"2026-05-19T17:58:41.209544Z","steps":["trace[1656377108] 'agreement among raft nodes before linearized reading' (duration: 1.999923429s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:41.209589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:40.620468Z","time spent":"589.113334ms","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:58:41.209496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.993912386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-19T17:58:41.209615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:39.209265Z","time spent":"2.00033908s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T17:58:41.209646Z","caller":"traceutil/trace.go:171","msg":"trace[1237489926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"1.994102161s","start":"2026-05-19T17:58:39.215532Z","end":"2026-05-19T17:58:41.209634Z","steps":["trace[1237489926] 'agreement among raft nodes before linearized reading' (duration: 1.993877155s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:58:41.209677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:39.215509Z","time spent":"1.994160243s","remote":"127.0.0.1:59146","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-05-19T17:58:41.209495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:40.893596Z","time spent":"315.88324ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T17:58:41.210121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:58:40.61653Z","time spent":"592.671581ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:05.457533Z","caller":"traceutil/trace.go:171","msg":"trace[635676540] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"122.449096ms","start":"2026-05-19T17:59:05.335041Z","end":"2026-05-19T17:59:05.45749Z","steps":["trace[635676540] 'process raft request' (duration: 122.081937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:06.17861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.715374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:59:06.178718Z","caller":"traceutil/trace.go:171","msg":"trace[1509049275] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"716.479375ms","start":"2026-05-19T17:59:05.462218Z","end":"2026-05-19T17:59:06.178698Z","steps":["trace[1509049275] 'process raft request' (duration: 176.591136ms)","trace[1509049275] 'compare' (duration: 539.530209ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:06.178777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:05.46219Z","time spent":"716.559307ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:06.504261Z","caller":"traceutil/trace.go:171","msg":"trace[106515175] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1216; }","duration":"487.379462ms","start":"2026-05-19T17:59:06.016852Z","end":"2026-05-19T17:59:06.504232Z","steps":["trace[106515175] 'read index received' (duration: 417.194096ms)","trace[106515175] 'applied index is now lower than readState.Index' (duration: 70.184076ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:59:06.504497Z","caller":"traceutil/trace.go:171","msg":"trace[2061704559] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"1.040739188s","start":"2026-05-19T17:59:05.463736Z","end":"2026-05-19T17:59:06.504475Z","steps":["trace[2061704559] 'process raft request' (duration: 1.040397019s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:06.504809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:05.463729Z","time spent":"1.040992476s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:06.504824Z","caller":"traceutil/trace.go:171","msg":"trace[758296260] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"1.040669227s","start":"2026-05-19T17:59:05.464122Z","end":"2026-05-19T17:59:06.504792Z","steps":["trace[758296260] 'process raft request' (duration: 1.04006821s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:06.504889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.666571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:59:06.504953Z","caller":"traceutil/trace.go:171","msg":"trace[364545521] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1174; }","duration":"331.822096ms","start":"2026-05-19T17:59:06.173116Z","end":"2026-05-19T17:59:06.504938Z","steps":["trace[364545521] 'agreement among raft nodes before linearized reading' (duration: 331.692902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:06.504997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:06.173092Z","time spent":"331.895547ms","remote":"127.0.0.1:48880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:59:06.504985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:05.464114Z","time spent":"1.040782631s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:06.504969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.092901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:59:06.505148Z","caller":"traceutil/trace.go:171","msg":"trace[172232844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1174; }","duration":"488.300157ms","start":"2026-05-19T17:59:06.016791Z","end":"2026-05-19T17:59:06.505091Z","steps":["trace[172232844] 'agreement among raft nodes before linearized reading' (duration: 487.925207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:06.505238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:06.016757Z","time spent":"488.468501ms","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T17:59:06.504529Z","caller":"traceutil/trace.go:171","msg":"trace[866793492] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"1.040943074s","start":"2026-05-19T17:59:05.463535Z","end":"2026-05-19T17:59:06.504478Z","steps":["trace[866793492] 'process raft request' (duration: 1.040559434s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:06.505832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:05.463521Z","time spent":"1.042249909s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:06.50536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.629433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.11\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T17:59:06.506049Z","caller":"traceutil/trace.go:171","msg":"trace[1325833392] range","detail":"{range_begin:/registry/masterleases/199.19.213.11; range_end:; response_count:1; response_revision:1174; }","duration":"302.351602ms","start":"2026-05-19T17:59:06.203682Z","end":"2026-05-19T17:59:06.506034Z","steps":["trace[1325833392] 'agreement among raft nodes before linearized reading' (duration: 301.475389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:06.506086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:06.203657Z","time spent":"302.420533ms","remote":"127.0.0.1:58890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.11\" "} {"level":"info","ts":"2026-05-19T17:59:06.505424Z","caller":"traceutil/trace.go:171","msg":"trace[1341260160] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"1.042942188s","start":"2026-05-19T17:59:05.462467Z","end":"2026-05-19T17:59:06.505409Z","steps":["trace[1341260160] 'process raft request' (duration: 971.675372ms)","trace[1341260160] 'compare' (duration: 69.829087ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:06.507038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:05.462442Z","time spent":"1.044023866s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:07.127872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.965366ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-19T17:59:07.128672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:06.507284Z","time spent":"621.378466ms","remote":"127.0.0.1:58890","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-19T17:59:07.128823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.054647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:59:07.128896Z","caller":"traceutil/trace.go:171","msg":"trace[1171790138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1174; }","duration":"110.18418ms","start":"2026-05-19T17:59:07.018694Z","end":"2026-05-19T17:59:07.128878Z","steps":["trace[1171790138] 'agreement among raft nodes before linearized reading' (duration: 110.058157ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:07.129355Z","caller":"traceutil/trace.go:171","msg":"trace[1428135891] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1220; }","duration":"109.82103ms","start":"2026-05-19T17:59:07.018759Z","end":"2026-05-19T17:59:07.12858Z","steps":["trace[1428135891] 'read index received' (duration: 73.061µs)","trace[1428135891] 'applied index is now lower than readState.Index' (duration: 109.742889ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:09.009963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144046083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:59:09.510323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144046083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:59:09.572426Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.006879038s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:59:09.572831Z","caller":"traceutil/trace.go:171","msg":"trace[1308593997] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"1.384887552s","start":"2026-05-19T17:59:08.187871Z","end":"2026-05-19T17:59:09.572759Z","steps":["trace[1308593997] 'process raft request' (duration: 1.384715908s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:09.572984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:08.187852Z","time spent":"1.385053426s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:09.621053Z","caller":"traceutil/trace.go:171","msg":"trace[1960493556] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"122.494909ms","start":"2026-05-19T17:59:09.498531Z","end":"2026-05-19T17:59:09.621026Z","steps":["trace[1960493556] 'process raft request' (duration: 93.930109ms)","trace[1960493556] 'compare' (duration: 28.380355ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:59:09.621193Z","caller":"traceutil/trace.go:171","msg":"trace[1870961702] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1223; }","duration":"1.111270875s","start":"2026-05-19T17:59:08.509889Z","end":"2026-05-19T17:59:09.621159Z","steps":["trace[1870961702] 'read index received' (duration: 1.062764765s)","trace[1870961702] 'applied index is now lower than readState.Index' (duration: 48.5034ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:09.621603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.947727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:59:09.621681Z","caller":"traceutil/trace.go:171","msg":"trace[1295495480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1179; }","duration":"673.071551ms","start":"2026-05-19T17:59:08.948591Z","end":"2026-05-19T17:59:09.621663Z","steps":["trace[1295495480] 'agreement among raft nodes before linearized reading' (duration: 672.947247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:09.621719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:08.948569Z","time spent":"673.142893ms","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:59:09.621731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109365934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T17:59:09.621779Z","caller":"traceutil/trace.go:171","msg":"trace[1526401843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1179; }","duration":"1.109467546s","start":"2026-05-19T17:59:08.512296Z","end":"2026-05-19T17:59:09.621764Z","steps":["trace[1526401843] 'agreement among raft nodes before linearized reading' (duration: 1.109275541s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:09.622123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:08.512175Z","time spent":"1.109932969s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T17:59:09.622181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112288232s","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-05-19T17:59:09.622262Z","caller":"traceutil/trace.go:171","msg":"trace[817469972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1179; }","duration":"1.112404805s","start":"2026-05-19T17:59:08.509846Z","end":"2026-05-19T17:59:09.622251Z","steps":["trace[817469972] 'agreement among raft nodes before linearized reading' (duration: 1.11222699s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:09.62232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:08.509825Z","time spent":"1.112479817s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:59:09.622388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111262955s","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-05-19T17:59:09.622561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112029914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T17:59:09.622628Z","caller":"traceutil/trace.go:171","msg":"trace[1453904326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1179; }","duration":"1.112121637s","start":"2026-05-19T17:59:08.510483Z","end":"2026-05-19T17:59:09.622604Z","steps":["trace[1453904326] 'agreement among raft nodes before linearized reading' (duration: 1.111969203s)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:09.622567Z","caller":"traceutil/trace.go:171","msg":"trace[620036613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1179; }","duration":"1.112094737s","start":"2026-05-19T17:59:08.510457Z","end":"2026-05-19T17:59:09.622552Z","steps":["trace[620036613] 'agreement among raft nodes before linearized reading' (duration: 1.111041219s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:09.622667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:08.510472Z","time spent":"1.112187271s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T17:59:09.622784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:08.510444Z","time spent":"1.112249652s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T17:59:09.770711Z","caller":"traceutil/trace.go:171","msg":"trace[440903883] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"142.87655ms","start":"2026-05-19T17:59:09.627809Z","end":"2026-05-19T17:59:09.770686Z","steps":["trace[440903883] 'process raft request' (duration: 142.748166ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:09.770749Z","caller":"traceutil/trace.go:171","msg":"trace[1684211689] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"143.183678ms","start":"2026-05-19T17:59:09.627526Z","end":"2026-05-19T17:59:09.77071Z","steps":["trace[1684211689] 'process raft request' (duration: 113.176731ms)","trace[1684211689] 'compare' (duration: 29.711349ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:59:09.77091Z","caller":"traceutil/trace.go:171","msg":"trace[690453703] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"141.703638ms","start":"2026-05-19T17:59:09.629194Z","end":"2026-05-19T17:59:09.770898Z","steps":["trace[690453703] 'process raft request' (duration: 141.446391ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:11.926208Z","caller":"traceutil/trace.go:171","msg":"trace[1863802306] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"343.734031ms","start":"2026-05-19T17:59:11.58124Z","end":"2026-05-19T17:59:11.924974Z","steps":["trace[1863802306] 'process raft request' (duration: 343.532876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:11.926384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:11.581224Z","time spent":"345.064837ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:11.928766Z","caller":"traceutil/trace.go:171","msg":"trace[454514515] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"300.506333ms","start":"2026-05-19T17:59:11.628238Z","end":"2026-05-19T17:59:11.928744Z","steps":["trace[454514515] 'process raft request' (duration: 300.275757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:11.92892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:11.628202Z","time spent":"300.635666ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:12.812899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.233696ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T17:59:12.813721Z","caller":"traceutil/trace.go:171","msg":"trace[1620685864] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1230; }","duration":"1.166529174s","start":"2026-05-19T17:59:11.647165Z","end":"2026-05-19T17:59:12.813694Z","steps":["trace[1620685864] 'read index received' (duration: 277.613073ms)","trace[1620685864] 'applied index is now lower than readState.Index' (duration: 888.913441ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:12.814002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166816912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T17:59:12.814057Z","caller":"traceutil/trace.go:171","msg":"trace[144796076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1185; }","duration":"1.166883524s","start":"2026-05-19T17:59:11.647157Z","end":"2026-05-19T17:59:12.814041Z","steps":["trace[144796076] 'agreement among raft nodes before linearized reading' (duration: 1.166677368s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:12.814106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:11.647092Z","time spent":"1.167002897s","remote":"127.0.0.1:59146","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-05-19T17:59:12.814436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039201227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-19T17:59:12.814567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.847999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:59:12.814659Z","caller":"traceutil/trace.go:171","msg":"trace[1971662515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1185; }","duration":"797.975382ms","start":"2026-05-19T17:59:12.016653Z","end":"2026-05-19T17:59:12.814628Z","steps":["trace[1971662515] 'agreement among raft nodes before linearized reading' (duration: 797.821688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:12.814706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:12.016631Z","time spent":"798.066235ms","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:59:12.814711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038606142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T17:59:12.814803Z","caller":"traceutil/trace.go:171","msg":"trace[767562272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1185; }","duration":"1.038725835s","start":"2026-05-19T17:59:11.776055Z","end":"2026-05-19T17:59:12.814781Z","steps":["trace[767562272] 'agreement among raft nodes before linearized reading' (duration: 1.038443388s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:12.81486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:11.776048Z","time spent":"1.038801248s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T17:59:12.81495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039066604s","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-05-19T17:59:12.815015Z","caller":"traceutil/trace.go:171","msg":"trace[2105554871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1185; }","duration":"1.039160687s","start":"2026-05-19T17:59:11.775839Z","end":"2026-05-19T17:59:12.815Z","steps":["trace[2105554871] 'agreement among raft nodes before linearized reading' (duration: 1.038957351s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:12.815048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:11.775831Z","time spent":"1.039210898s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:59:12.815047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.63359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:59:12.815088Z","caller":"traceutil/trace.go:171","msg":"trace[398951649] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1185; }","duration":"895.717372ms","start":"2026-05-19T17:59:11.919359Z","end":"2026-05-19T17:59:12.815076Z","steps":["trace[398951649] 'agreement among raft nodes before linearized reading' (duration: 895.63732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:12.815135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:11.919337Z","time spent":"895.788994ms","remote":"127.0.0.1:58958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:59:12.815175Z","caller":"traceutil/trace.go:171","msg":"trace[271140066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1185; }","duration":"1.03930187s","start":"2026-05-19T17:59:11.775178Z","end":"2026-05-19T17:59:12.814479Z","steps":["trace[271140066] 'agreement among raft nodes before linearized reading' (duration: 1.039161456s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:12.815248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:11.775156Z","time spent":"1.040075031s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T17:59:13.179604Z","caller":"traceutil/trace.go:171","msg":"trace[489586675] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"360.544008ms","start":"2026-05-19T17:59:12.819035Z","end":"2026-05-19T17:59:13.179579Z","steps":["trace[489586675] 'process raft request' (duration: 360.379683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:13.179751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:12.819011Z","time spent":"360.666012ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:13.30239Z","caller":"traceutil/trace.go:171","msg":"trace[461953302] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"482.995555ms","start":"2026-05-19T17:59:12.819367Z","end":"2026-05-19T17:59:13.302363Z","steps":["trace[461953302] 'process raft request' (duration: 469.034673ms)","trace[461953302] 'compare' (duration: 13.753896ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:13.302628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:12.819361Z","time spent":"483.108708ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:13.303031Z","caller":"traceutil/trace.go:171","msg":"trace[956158075] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"483.5927ms","start":"2026-05-19T17:59:12.819413Z","end":"2026-05-19T17:59:13.303005Z","steps":["trace[956158075] 'process raft request' (duration: 482.907532ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:13.3031Z","caller":"traceutil/trace.go:171","msg":"trace[1479050813] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"482.177293ms","start":"2026-05-19T17:59:12.820905Z","end":"2026-05-19T17:59:13.303083Z","steps":["trace[1479050813] 'process raft request' (duration: 482.03881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:13.303162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:12.819409Z","time spent":"483.681313ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:13.303196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:12.820897Z","time spent":"482.248185ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:22.708846Z","caller":"traceutil/trace.go:171","msg":"trace[1386710157] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1218; }","duration":"103.333398ms","start":"2026-05-19T17:59:22.605292Z","end":"2026-05-19T17:59:22.708625Z","steps":["trace[1386710157] 'process raft request' (duration: 103.199615ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:22.873164Z","caller":"traceutil/trace.go:171","msg":"trace[1512999082] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"140.25783ms","start":"2026-05-19T17:59:22.732867Z","end":"2026-05-19T17:59:22.873125Z","steps":["trace[1512999082] 'process raft request' (duration: 140.029784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:28.499163Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144046331,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:59:29.000174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144046331,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:59:29.452194Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.467302634s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T17:59:29.452557Z","caller":"traceutil/trace.go:171","msg":"trace[1260071451] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1340; }","duration":"1.454096012s","start":"2026-05-19T17:59:27.998442Z","end":"2026-05-19T17:59:29.452538Z","steps":["trace[1260071451] 'read index received' (duration: 1.453845126s)","trace[1260071451] 'applied index is now lower than readState.Index' (duration: 250.096µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:59:29.452645Z","caller":"traceutil/trace.go:171","msg":"trace[1511440782] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"1.467797967s","start":"2026-05-19T17:59:27.984809Z","end":"2026-05-19T17:59:29.452607Z","steps":["trace[1511440782] 'process raft request' (duration: 1.46752637s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:29.452788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454321128s","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-05-19T17:59:29.452819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:27.984785Z","time spent":"1.4679429s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:29.452864Z","caller":"traceutil/trace.go:171","msg":"trace[1502443056] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"1.45440654s","start":"2026-05-19T17:59:27.998436Z","end":"2026-05-19T17:59:29.452843Z","steps":["trace[1502443056] 'agreement among raft nodes before linearized reading' (duration: 1.454203975s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:29.452922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:27.998389Z","time spent":"1.454499973s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T17:59:29.452998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.675335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:59:29.453056Z","caller":"traceutil/trace.go:171","msg":"trace[839962669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"506.775328ms","start":"2026-05-19T17:59:28.946268Z","end":"2026-05-19T17:59:29.453044Z","steps":["trace[839962669] 'agreement among raft nodes before linearized reading' (duration: 506.686985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:29.453082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:28.94625Z","time spent":"506.82722ms","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:59:29.453003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.931142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:59:29.453202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.436637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:59:29.453223Z","caller":"traceutil/trace.go:171","msg":"trace[207505510] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1288; }","duration":"254.189849ms","start":"2026-05-19T17:59:29.199023Z","end":"2026-05-19T17:59:29.453213Z","steps":["trace[207505510] 'agreement among raft nodes before linearized reading' (duration: 253.937963ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:29.453234Z","caller":"traceutil/trace.go:171","msg":"trace[1684343806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"436.496018ms","start":"2026-05-19T17:59:29.016728Z","end":"2026-05-19T17:59:29.453224Z","steps":["trace[1684343806] 'agreement among raft nodes before linearized reading' (duration: 436.455747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:29.453264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:29.016713Z","time spent":"436.541349ms","remote":"127.0.0.1:58878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:59:29.453317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.443455ms","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-05-19T17:59:29.453389Z","caller":"traceutil/trace.go:171","msg":"trace[114318543] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1288; }","duration":"275.556688ms","start":"2026-05-19T17:59:29.177819Z","end":"2026-05-19T17:59:29.453376Z","steps":["trace[114318543] 'agreement among raft nodes before linearized reading' (duration: 275.406985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:30.564142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.609083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:59:30.564331Z","caller":"traceutil/trace.go:171","msg":"trace[1182638346] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1341; }","duration":"1.105682676s","start":"2026-05-19T17:59:29.458629Z","end":"2026-05-19T17:59:30.564312Z","steps":["trace[1182638346] 'read index received' (duration: 83.220224ms)","trace[1182638346] 'applied index is now lower than readState.Index' (duration: 1.022461252s)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:59:30.564505Z","caller":"traceutil/trace.go:171","msg":"trace[523509010] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"1.106298792s","start":"2026-05-19T17:59:29.458168Z","end":"2026-05-19T17:59:30.564467Z","steps":["trace[523509010] 'process raft request' (duration: 646.271128ms)","trace[523509010] 'compare' (duration: 459.48879ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:59:30.564493Z","caller":"traceutil/trace.go:171","msg":"trace[2145542233] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"1.106251851s","start":"2026-05-19T17:59:29.458208Z","end":"2026-05-19T17:59:30.56446Z","steps":["trace[2145542233] 'process raft request' (duration: 1.106035496s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:30.56461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105968764s","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-05-19T17:59:30.564641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:29.458152Z","time spent":"1.106418335s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:30.564658Z","caller":"traceutil/trace.go:171","msg":"trace[210512958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1291; }","duration":"1.106050456s","start":"2026-05-19T17:59:29.458597Z","end":"2026-05-19T17:59:30.564647Z","steps":["trace[210512958] 'agreement among raft nodes before linearized reading' (duration: 1.105882842s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:30.564688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:29.458584Z","time spent":"1.106096337s","remote":"127.0.0.1:59146","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-05-19T17:59:30.564679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:29.458186Z","time spent":"1.106414625s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:30.566096Z","caller":"traceutil/trace.go:171","msg":"trace[1455402598] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"403.036309ms","start":"2026-05-19T17:59:30.163029Z","end":"2026-05-19T17:59:30.566065Z","steps":["trace[1455402598] 'process raft request' (duration: 402.848224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:30.566242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:30.162999Z","time spent":"403.179062ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:30.566218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.592617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:59:30.566339Z","caller":"traceutil/trace.go:171","msg":"trace[320110229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"549.760062ms","start":"2026-05-19T17:59:30.01656Z","end":"2026-05-19T17:59:30.56632Z","steps":["trace[320110229] 'agreement among raft nodes before linearized reading' (duration: 549.545356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:30.566393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:30.016544Z","time spent":"549.838114ms","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T17:59:30.566772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106799545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T17:59:30.566841Z","caller":"traceutil/trace.go:171","msg":"trace[513795784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"1.107550955s","start":"2026-05-19T17:59:29.459275Z","end":"2026-05-19T17:59:30.566826Z","steps":["trace[513795784] 'agreement among raft nodes before linearized reading' (duration: 1.106728194s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:30.566915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:29.459267Z","time spent":"1.107635657s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T17:59:47.918756Z","caller":"traceutil/trace.go:171","msg":"trace[1506979260] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"300.698937ms","start":"2026-05-19T17:59:47.618034Z","end":"2026-05-19T17:59:47.918733Z","steps":["trace[1506979260] 'read index received' (duration: 300.524253ms)","trace[1506979260] 'applied index is now lower than readState.Index' (duration: 173.634µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:47.918961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.918013ms","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-05-19T17:59:47.919004Z","caller":"traceutil/trace.go:171","msg":"trace[2117467804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1375; }","duration":"301.008365ms","start":"2026-05-19T17:59:47.617981Z","end":"2026-05-19T17:59:47.91899Z","steps":["trace[2117467804] 'agreement among raft nodes before linearized reading' (duration: 300.852551ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:47.919009Z","caller":"traceutil/trace.go:171","msg":"trace[791108835] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"405.152446ms","start":"2026-05-19T17:59:47.513828Z","end":"2026-05-19T17:59:47.91898Z","steps":["trace[791108835] 'process raft request' (duration: 404.757075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:47.919042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:47.617962Z","time spent":"301.072216ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:59:47.919143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:47.513801Z","time spent":"405.268288ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:48.829003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.983432ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:59:48.829163Z","caller":"traceutil/trace.go:171","msg":"trace[1470091557] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1375; }","duration":"666.179897ms","start":"2026-05-19T17:59:48.162961Z","end":"2026-05-19T17:59:48.829141Z","steps":["trace[1470091557] 'range keys from in-memory index tree' (duration: 665.953041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:48.829198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.101254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:59:48.829302Z","caller":"traceutil/trace.go:171","msg":"trace[2030425589] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1432; }","duration":"117.990818ms","start":"2026-05-19T17:59:48.711299Z","end":"2026-05-19T17:59:48.82929Z","steps":["trace[2030425589] 'read index received' (duration: 48.651µs)","trace[2030425589] 'applied index is now lower than readState.Index' (duration: 117.941277ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:59:48.829384Z","caller":"traceutil/trace.go:171","msg":"trace[1505691382] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"905.998285ms","start":"2026-05-19T17:59:47.923335Z","end":"2026-05-19T17:59:48.829333Z","steps":["trace[1505691382] 'process raft request' (duration: 418.688165ms)","trace[1505691382] 'compare' (duration: 487.014022ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:48.829433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.141931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T17:59:48.829453Z","caller":"traceutil/trace.go:171","msg":"trace[1083339826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1376; }","duration":"118.199043ms","start":"2026-05-19T17:59:48.711248Z","end":"2026-05-19T17:59:48.829447Z","steps":["trace[1083339826] 'agreement among raft nodes before linearized reading' (duration: 118.10232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:48.829536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:47.923311Z","time spent":"906.144208ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:48.829684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.101435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T17:59:48.829703Z","caller":"traceutil/trace.go:171","msg":"trace[478402562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1376; }","duration":"102.171707ms","start":"2026-05-19T17:59:48.727527Z","end":"2026-05-19T17:59:48.829699Z","steps":["trace[478402562] 'agreement among raft nodes before linearized reading' (duration: 102.121106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:48.82976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.18099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T17:59:48.829796Z","caller":"traceutil/trace.go:171","msg":"trace[2030444779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1376; }","duration":"110.260552ms","start":"2026-05-19T17:59:48.719527Z","end":"2026-05-19T17:59:48.829787Z","steps":["trace[2030444779] 'agreement among raft nodes before linearized reading' (duration: 110.071517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:48.829815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.987552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T17:59:48.829867Z","caller":"traceutil/trace.go:171","msg":"trace[1078237296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1376; }","duration":"102.060934ms","start":"2026-05-19T17:59:48.727791Z","end":"2026-05-19T17:59:48.829852Z","steps":["trace[1078237296] 'agreement among raft nodes before linearized reading' (duration: 101.864429ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:49.059163Z","caller":"traceutil/trace.go:171","msg":"trace[2125013978] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"225.664572ms","start":"2026-05-19T17:59:48.833472Z","end":"2026-05-19T17:59:49.059137Z","steps":["trace[2125013978] 'process raft request' (duration: 162.078441ms)","trace[2125013978] 'compare' (duration: 63.450968ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:59:49.107585Z","caller":"traceutil/trace.go:171","msg":"trace[538147837] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"273.727819ms","start":"2026-05-19T17:59:48.833829Z","end":"2026-05-19T17:59:49.107557Z","steps":["trace[538147837] 'process raft request' (duration: 273.529674ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:49.107891Z","caller":"traceutil/trace.go:171","msg":"trace[383204995] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"274.025117ms","start":"2026-05-19T17:59:48.833842Z","end":"2026-05-19T17:59:49.107867Z","steps":["trace[383204995] 'process raft request' (duration: 273.679138ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:49.108124Z","caller":"traceutil/trace.go:171","msg":"trace[1063763979] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1433; }","duration":"159.846002ms","start":"2026-05-19T17:59:48.948266Z","end":"2026-05-19T17:59:49.108112Z","steps":["trace[1063763979] 'read index received' (duration: 47.368861ms)","trace[1063763979] 'applied index is now lower than readState.Index' (duration: 112.475931ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:49.108318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.032767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:59:49.108369Z","caller":"traceutil/trace.go:171","msg":"trace[1920718270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1380; }","duration":"160.13488ms","start":"2026-05-19T17:59:48.94822Z","end":"2026-05-19T17:59:49.108354Z","steps":["trace[1920718270] 'agreement among raft nodes before linearized reading' (duration: 160.043318ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:49.108605Z","caller":"traceutil/trace.go:171","msg":"trace[668317199] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"274.640904ms","start":"2026-05-19T17:59:48.833946Z","end":"2026-05-19T17:59:49.108587Z","steps":["trace[668317199] 'process raft request' (duration: 273.843372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:50.272862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144046545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T17:59:50.773574Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.079995414s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-19T17:59:50.773654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144046545,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T17:59:50.773856Z","caller":"traceutil/trace.go:171","msg":"trace[1434557786] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"1.080352183s","start":"2026-05-19T17:59:49.69349Z","end":"2026-05-19T17:59:50.773842Z","steps":["trace[1434557786] 'process raft request' (duration: 1.080200449s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:50.773923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:49.693466Z","time spent":"1.080420315s","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:59:51.959569Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.185808397s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-19T17:59:52.016621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000002721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-19T17:59:52.017265Z","caller":"traceutil/trace.go:171","msg":"trace[1680628673] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000694039s","start":"2026-05-19T17:59:50.016553Z","end":"2026-05-19T17:59:52.017247Z","steps":["trace[1680628673] 'agreement among raft nodes before linearized reading' (duration: 2.000026152s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:52.017386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:50.016538Z","time spent":"2.000833443s","remote":"127.0.0.1:58862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/19 17:59:52 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T17:59:52.319688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.682046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:59:52.319798Z","caller":"traceutil/trace.go:171","msg":"trace[1124612484] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1438; }","duration":"2.547538392s","start":"2026-05-19T17:59:49.772244Z","end":"2026-05-19T17:59:52.319782Z","steps":["trace[1124612484] 'read index received' (duration: 1.001540876s)","trace[1124612484] 'applied index is now lower than readState.Index' (duration: 1.545996146s)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:52.319838Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":9143325672144046552,"received-request-id":9143325672144046545} {"level":"warn","ts":"2026-05-19T17:59:52.319925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.547670346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:59:52.319983Z","caller":"traceutil/trace.go:171","msg":"trace[1574664186] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1383; }","duration":"2.547756087s","start":"2026-05-19T17:59:49.77221Z","end":"2026-05-19T17:59:52.319966Z","steps":["trace[1574664186] 'agreement among raft nodes before linearized reading' (duration: 2.547641204s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:52.320028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:49.772195Z","time spent":"2.547821109s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-19T17:59:52.321011Z","caller":"traceutil/trace.go:171","msg":"trace[1060698081] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"2.39480553s","start":"2026-05-19T17:59:49.925009Z","end":"2026-05-19T17:59:52.319815Z","steps":["trace[1060698081] 'process raft request' (duration: 2.034832916s)","trace[1060698081] 'compare' (duration: 359.253185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:52.321151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:49.924984Z","time spent":"2.396101984s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:53.081259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.529473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:59:53.081581Z","caller":"traceutil/trace.go:171","msg":"trace[528542417] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"1.915215476s","start":"2026-05-19T17:59:51.166324Z","end":"2026-05-19T17:59:53.081539Z","steps":["trace[528542417] 'process raft request' (duration: 1.393725587s)","trace[528542417] 'compare' (duration: 520.207084ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:53.081754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:51.166298Z","time spent":"1.91536409s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:53.794737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.31371ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T17:59:53.79543Z","caller":"traceutil/trace.go:171","msg":"trace[788895486] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"1.606306991s","start":"2026-05-19T17:59:52.189092Z","end":"2026-05-19T17:59:53.795399Z","steps":["trace[788895486] 'process raft request' (duration: 1.605747736s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:53.795589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:52.189068Z","time spent":"1.606444644s","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:59:54.018257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00071908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-19T17:59:54.018341Z","caller":"traceutil/trace.go:171","msg":"trace[2142624108] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000848323s","start":"2026-05-19T17:59:52.017474Z","end":"2026-05-19T17:59:54.018322Z","steps":["trace[2142624108] 'agreement among raft nodes before linearized reading' (duration: 2.00073884s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:54.018393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:52.017457Z","time spent":"2.000925705s","remote":"127.0.0.1:58878","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-05-19T17:59:54.194192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.741364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:59:54.194377Z","caller":"traceutil/trace.go:171","msg":"trace[1731102207] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"393.992689ms","start":"2026-05-19T17:59:53.800367Z","end":"2026-05-19T17:59:54.194359Z","steps":["trace[1731102207] 'process raft request' (duration: 393.920757ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:54.194382Z","caller":"traceutil/trace.go:171","msg":"trace[370901190] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"1.868588436s","start":"2026-05-19T17:59:52.325704Z","end":"2026-05-19T17:59:54.194292Z","steps":["trace[370901190] 'process raft request' (duration: 1.469680587s)","trace[370901190] 'compare' (duration: 398.486117ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:54.194445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:53.800344Z","time spent":"394.071161ms","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":843,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:59:54.194569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:52.325686Z","time spent":"1.868793621s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:59:54.194658Z","caller":"traceutil/trace.go:171","msg":"trace[1666621227] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1440; }","duration":"1.874811102s","start":"2026-05-19T17:59:52.319823Z","end":"2026-05-19T17:59:54.194634Z","steps":["trace[1666621227] 'read index received' (duration: 240.25196ms)","trace[1666621227] 'applied index is now lower than readState.Index' (duration: 1.634557081s)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:54.194865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.082995413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T17:59:54.194904Z","caller":"traceutil/trace.go:171","msg":"trace[2145198864] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1387; }","duration":"3.083064684s","start":"2026-05-19T17:59:51.11183Z","end":"2026-05-19T17:59:54.194894Z","steps":["trace[2145198864] 'agreement among raft nodes before linearized reading' (duration: 3.082927641s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:54.194887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.012407905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-05-19T17:59:54.194938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:51.111817Z","time spent":"3.083113496s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T17:59:54.19495Z","caller":"traceutil/trace.go:171","msg":"trace[1478520338] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1387; }","duration":"3.012529688s","start":"2026-05-19T17:59:51.182402Z","end":"2026-05-19T17:59:54.194932Z","steps":["trace[1478520338] 'agreement among raft nodes before linearized reading' (duration: 3.012296682s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:54.194992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:51.182383Z","time spent":"3.012601711s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-19T17:59:54.194865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.08289029s","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-05-19T17:59:54.195048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.57714763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:59:54.195093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.519132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:59:54.195054Z","caller":"traceutil/trace.go:171","msg":"trace[1383602182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1387; }","duration":"3.083086765s","start":"2026-05-19T17:59:51.111953Z","end":"2026-05-19T17:59:54.195039Z","steps":["trace[1383602182] 'agreement among raft nodes before linearized reading' (duration: 3.082763647s)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:54.195134Z","caller":"traceutil/trace.go:171","msg":"trace[94068767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1387; }","duration":"168.600934ms","start":"2026-05-19T17:59:54.026522Z","end":"2026-05-19T17:59:54.195122Z","steps":["trace[94068767] 'agreement among raft nodes before linearized reading' (duration: 168.525642ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:54.19512Z","caller":"traceutil/trace.go:171","msg":"trace[1665596990] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1387; }","duration":"2.577288074s","start":"2026-05-19T17:59:51.617818Z","end":"2026-05-19T17:59:54.195106Z","steps":["trace[1665596990] 'agreement among raft nodes before linearized reading' (duration: 2.57714865s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:54.195168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.132179681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-19T17:59:54.19515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:51.11195Z","time spent":"3.083187138s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T17:59:54.195204Z","caller":"traceutil/trace.go:171","msg":"trace[593299896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1387; }","duration":"3.132250943s","start":"2026-05-19T17:59:51.062944Z","end":"2026-05-19T17:59:54.195195Z","steps":["trace[593299896] 'agreement among raft nodes before linearized reading' (duration: 3.132158411s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:54.195244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:51.062926Z","time spent":"3.132311954s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T17:59:54.195205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:51.617799Z","time spent":"2.577392406s","remote":"127.0.0.1:48854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:59:54.195189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.837224786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:59:54.195368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.083198159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T17:59:54.195401Z","caller":"traceutil/trace.go:171","msg":"trace[1939573998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1387; }","duration":"3.083237209s","start":"2026-05-19T17:59:51.112154Z","end":"2026-05-19T17:59:54.195391Z","steps":["trace[1939573998] 'agreement among raft nodes before linearized reading' (duration: 3.083151007s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:54.195443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:51.11215Z","time spent":"3.083286941s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T17:59:54.195402Z","caller":"traceutil/trace.go:171","msg":"trace[1211082441] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1387; }","duration":"2.837469532s","start":"2026-05-19T17:59:51.357916Z","end":"2026-05-19T17:59:54.195386Z","steps":["trace[1211082441] 'agreement among raft nodes before linearized reading' (duration: 2.837240456s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:54.195518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:51.357897Z","time spent":"2.837607277s","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:59:54.194884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.36110538s","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-05-19T17:59:54.196597Z","caller":"traceutil/trace.go:171","msg":"trace[954674218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1387; }","duration":"3.362858037s","start":"2026-05-19T17:59:50.833727Z","end":"2026-05-19T17:59:54.196585Z","steps":["trace[954674218] 'agreement among raft nodes before linearized reading' (duration: 3.361053068s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:54.196663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:50.833711Z","time spent":"3.362939638s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T17:59:54.577647Z","caller":"traceutil/trace.go:171","msg":"trace[2022110968] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"377.157321ms","start":"2026-05-19T17:59:54.20046Z","end":"2026-05-19T17:59:54.577617Z","steps":["trace[2022110968] 'process raft request' (duration: 368.735347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:54.577803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:54.200439Z","time spent":"377.297584ms","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:59:55.120584Z","caller":"traceutil/trace.go:171","msg":"trace[646828111] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"919.831714ms","start":"2026-05-19T17:59:54.200733Z","end":"2026-05-19T17:59:55.120565Z","steps":["trace[646828111] 'process raft request' (duration: 919.769843ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:55.120644Z","caller":"traceutil/trace.go:171","msg":"trace[100127097] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"919.955487ms","start":"2026-05-19T17:59:54.200648Z","end":"2026-05-19T17:59:55.120604Z","steps":["trace[100127097] 'process raft request' (duration: 919.700791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:55.120696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:54.200722Z","time spent":"919.920556ms","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T17:59:55.120802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:54.200632Z","time spent":"920.08601ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:55.120967Z","caller":"traceutil/trace.go:171","msg":"trace[622657818] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"920.141292ms","start":"2026-05-19T17:59:54.200801Z","end":"2026-05-19T17:59:55.120942Z","steps":["trace[622657818] 'process raft request' (duration: 919.734802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:55.121117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:54.200792Z","time spent":"920.249265ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:55.121331Z","caller":"traceutil/trace.go:171","msg":"trace[967490424] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"920.505781ms","start":"2026-05-19T17:59:54.200813Z","end":"2026-05-19T17:59:55.121319Z","steps":["trace[967490424] 'process raft request' (duration: 919.997618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:55.121394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:54.200803Z","time spent":"920.554853ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:55.121637Z","caller":"traceutil/trace.go:171","msg":"trace[1648754448] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"920.728478ms","start":"2026-05-19T17:59:54.200881Z","end":"2026-05-19T17:59:55.121609Z","steps":["trace[1648754448] 'process raft request' (duration: 920.331467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:55.121766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:54.200873Z","time spent":"920.82462ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:55.121816Z","caller":"traceutil/trace.go:171","msg":"trace[1943191882] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"917.729257ms","start":"2026-05-19T17:59:54.204069Z","end":"2026-05-19T17:59:55.121798Z","steps":["trace[1943191882] 'process raft request' (duration: 917.440169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:55.121902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:54.204024Z","time spent":"917.834909ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:55.122267Z","caller":"traceutil/trace.go:171","msg":"trace[845021960] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"917.955653ms","start":"2026-05-19T17:59:54.204296Z","end":"2026-05-19T17:59:55.122251Z","steps":["trace[845021960] 'process raft request' (duration: 917.277935ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:55.122198Z","caller":"traceutil/trace.go:171","msg":"trace[239053711] linearizableReadLoop","detail":"{readStateIndex:1451; appliedIndex:1445; }","duration":"919.821713ms","start":"2026-05-19T17:59:54.202363Z","end":"2026-05-19T17:59:55.122185Z","steps":["trace[239053711] 'read index received' (duration: 366.846177ms)","trace[239053711] 'applied index is now lower than readState.Index' (duration: 552.974686ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:55.12233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:54.204281Z","time spent":"918.017814ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:55.122707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.333356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-19T17:59:55.122761Z","caller":"traceutil/trace.go:171","msg":"trace[1437410388] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1396; }","duration":"920.407709ms","start":"2026-05-19T17:59:54.202335Z","end":"2026-05-19T17:59:55.122743Z","steps":["trace[1437410388] 'agreement among raft nodes before linearized reading' (duration: 920.252284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:55.122794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:54.202325Z","time spent":"920.459311ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-19T17:59:55.123945Z","caller":"traceutil/trace.go:171","msg":"trace[1461915029] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"794.922071ms","start":"2026-05-19T17:59:54.329004Z","end":"2026-05-19T17:59:55.123926Z","steps":["trace[1461915029] 'process raft request' (duration: 793.106463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:55.124093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:54.328977Z","time spent":"795.067745ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:55.452138Z","caller":"traceutil/trace.go:171","msg":"trace[568773777] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"709.345765ms","start":"2026-05-19T17:59:54.742766Z","end":"2026-05-19T17:59:55.452112Z","steps":["trace[568773777] 'process raft request' (duration: 646.647008ms)","trace[568773777] 'compare' (duration: 62.392629ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:55.452307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:54.74274Z","time spent":"709.480868ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:55.53016Z","caller":"traceutil/trace.go:171","msg":"trace[1832569182] linearizableReadLoop","detail":"{readStateIndex:1456; appliedIndex:1455; }","duration":"134.24555ms","start":"2026-05-19T17:59:55.39589Z","end":"2026-05-19T17:59:55.530135Z","steps":["trace[1832569182] 'read index received' (duration: 134.071625ms)","trace[1832569182] 'applied index is now lower than readState.Index' (duration: 172.925µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:59:55.530234Z","caller":"traceutil/trace.go:171","msg":"trace[431508354] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"403.210874ms","start":"2026-05-19T17:59:55.126992Z","end":"2026-05-19T17:59:55.530202Z","steps":["trace[431508354] 'process raft request' (duration: 402.994538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:55.530324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.409795ms","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-05-19T17:59:55.530524Z","caller":"traceutil/trace.go:171","msg":"trace[1921058152] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1398; }","duration":"134.666032ms","start":"2026-05-19T17:59:55.395845Z","end":"2026-05-19T17:59:55.530511Z","steps":["trace[1921058152] 'agreement among raft nodes before linearized reading' (duration: 134.390334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:55.530636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:55.126976Z","time spent":"403.480651ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:57.047562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.773313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:59:57.047773Z","caller":"traceutil/trace.go:171","msg":"trace[1631882991] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"793.507194ms","start":"2026-05-19T17:59:56.254233Z","end":"2026-05-19T17:59:57.04774Z","steps":["trace[1631882991] 'process raft request' (duration: 499.496825ms)","trace[1631882991] 'compare' (duration: 293.623129ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:59:57.048139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:56.254207Z","time spent":"793.6975ms","remote":"127.0.0.1:58890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:57.531579Z","caller":"traceutil/trace.go:171","msg":"trace[928414033] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"401.365914ms","start":"2026-05-19T17:59:57.130194Z","end":"2026-05-19T17:59:57.53156Z","steps":["trace[928414033] 'process raft request' (duration: 401.248751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:57.531708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:57.130177Z","time spent":"401.475297ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:57.550931Z","caller":"traceutil/trace.go:171","msg":"trace[1765079562] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"420.096252ms","start":"2026-05-19T17:59:57.130813Z","end":"2026-05-19T17:59:57.55091Z","steps":["trace[1765079562] 'process raft request' (duration: 419.954849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:57.551062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:57.130801Z","time spent":"420.189344ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:57.551091Z","caller":"traceutil/trace.go:171","msg":"trace[2059050049] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"420.178935ms","start":"2026-05-19T17:59:57.130904Z","end":"2026-05-19T17:59:57.551083Z","steps":["trace[2059050049] 'process raft request' (duration: 419.970689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:57.551134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:57.13088Z","time spent":"420.232666ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:57.551339Z","caller":"traceutil/trace.go:171","msg":"trace[962832001] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"419.733791ms","start":"2026-05-19T17:59:57.131599Z","end":"2026-05-19T17:59:57.551333Z","steps":["trace[962832001] 'process raft request' (duration: 419.451634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:57.551383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:57.131584Z","time spent":"419.773963ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:59:57.551365Z","caller":"traceutil/trace.go:171","msg":"trace[1862746628] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"419.723841ms","start":"2026-05-19T17:59:57.131603Z","end":"2026-05-19T17:59:57.551327Z","steps":["trace[1862746628] 'process raft request' (duration: 419.596768ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:59:57.551394Z","caller":"traceutil/trace.go:171","msg":"trace[1958212487] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"418.813328ms","start":"2026-05-19T17:59:57.132543Z","end":"2026-05-19T17:59:57.551356Z","steps":["trace[1958212487] 'process raft request' (duration: 418.715766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:59:57.55155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:57.132533Z","time spent":"418.994124ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:59:57.551551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:59:57.131593Z","time spent":"419.863386ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:00:01.15906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.558613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:00:01.159212Z","caller":"traceutil/trace.go:171","msg":"trace[585067937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1415; }","duration":"144.766089ms","start":"2026-05-19T18:00:01.014431Z","end":"2026-05-19T18:00:01.159197Z","steps":["trace[585067937] 'range keys from in-memory index tree' (duration: 144.456271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:01.159056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.550204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:00:01.159307Z","caller":"traceutil/trace.go:171","msg":"trace[422541297] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1415; }","duration":"141.861562ms","start":"2026-05-19T18:00:01.017418Z","end":"2026-05-19T18:00:01.15928Z","steps":["trace[422541297] 'range keys from in-memory index tree' (duration: 141.468932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:01.678924Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325672144046617,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T18:00:01.808188Z","caller":"traceutil/trace.go:171","msg":"trace[719963364] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"631.498254ms","start":"2026-05-19T18:00:01.176652Z","end":"2026-05-19T18:00:01.80815Z","steps":["trace[719963364] 'process raft request' (duration: 609.562221ms)","trace[719963364] 'compare' (duration: 21.43516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:00:01.808364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:00:01.175794Z","time spent":"632.499002ms","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:00:01.814812Z","caller":"traceutil/trace.go:171","msg":"trace[1561473887] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1476; }","duration":"636.494657ms","start":"2026-05-19T18:00:01.178281Z","end":"2026-05-19T18:00:01.814776Z","steps":["trace[1561473887] 'read index received' (duration: 607.891397ms)","trace[1561473887] 'applied index is now lower than readState.Index' (duration: 28.60163ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:00:01.814969Z","caller":"traceutil/trace.go:171","msg":"trace[1334533429] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"635.890802ms","start":"2026-05-19T18:00:01.179068Z","end":"2026-05-19T18:00:01.814959Z","steps":["trace[1334533429] 'process raft request' (duration: 635.43704ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:00:01.815159Z","caller":"traceutil/trace.go:171","msg":"trace[963166272] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"250.083181ms","start":"2026-05-19T18:00:01.565029Z","end":"2026-05-19T18:00:01.815112Z","steps":["trace[963166272] 'process raft request' (duration: 249.643029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:01.815274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.985972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-19T18:00:01.815327Z","caller":"traceutil/trace.go:171","msg":"trace[839705472] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1420; }","duration":"637.085794ms","start":"2026-05-19T18:00:01.17823Z","end":"2026-05-19T18:00:01.815316Z","steps":["trace[839705472] 'agreement among raft nodes before linearized reading' (duration: 636.891978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:01.815355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:00:01.178212Z","time spent":"637.136165ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-19T18:00:01.815544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:00:01.179026Z","time spent":"635.973784ms","remote":"127.0.0.1:58930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:00:01.820671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.038506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T18:00:01.820981Z","caller":"traceutil/trace.go:171","msg":"trace[1315571392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1420; }","duration":"248.390016ms","start":"2026-05-19T18:00:01.572563Z","end":"2026-05-19T18:00:01.820953Z","steps":["trace[1315571392] 'agreement among raft nodes before linearized reading' (duration: 247.900142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:01.821365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.519046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T18:00:01.821409Z","caller":"traceutil/trace.go:171","msg":"trace[1520201092] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1420; }","duration":"249.569937ms","start":"2026-05-19T18:00:01.571827Z","end":"2026-05-19T18:00:01.821397Z","steps":["trace[1520201092] 'agreement among raft nodes before linearized reading' (duration: 249.476364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:01.821516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.976465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T18:00:01.821583Z","caller":"traceutil/trace.go:171","msg":"trace[1016650020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1420; }","duration":"251.086828ms","start":"2026-05-19T18:00:01.570478Z","end":"2026-05-19T18:00:01.821565Z","steps":["trace[1016650020] 'agreement among raft nodes before linearized reading' (duration: 249.928057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:01.821048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.107425ms","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-05-19T18:00:01.82165Z","caller":"traceutil/trace.go:171","msg":"trace[1304480589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1420; }","duration":"249.744531ms","start":"2026-05-19T18:00:01.571894Z","end":"2026-05-19T18:00:01.821638Z","steps":["trace[1304480589] 'agreement among raft nodes before linearized reading' (duration: 249.036492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:01.821879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.16047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T18:00:01.821965Z","caller":"traceutil/trace.go:171","msg":"trace[1874396839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1420; }","duration":"248.288543ms","start":"2026-05-19T18:00:01.573663Z","end":"2026-05-19T18:00:01.821951Z","steps":["trace[1874396839] 'agreement among raft nodes before linearized reading' (duration: 247.953464ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:00:20.069425Z","caller":"traceutil/trace.go:171","msg":"trace[1522523344] linearizableReadLoop","detail":"{readStateIndex:1585; appliedIndex:1584; }","duration":"109.401739ms","start":"2026-05-19T18:00:19.960002Z","end":"2026-05-19T18:00:20.069404Z","steps":["trace[1522523344] 'read index received' (duration: 109.236255ms)","trace[1522523344] 'applied index is now lower than readState.Index' (duration: 164.494µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:00:20.069536Z","caller":"traceutil/trace.go:171","msg":"trace[163941640] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"110.070229ms","start":"2026-05-19T18:00:19.959456Z","end":"2026-05-19T18:00:20.069526Z","steps":["trace[163941640] 'process raft request' (duration: 109.73644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:20.069727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.702128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T18:00:20.069787Z","caller":"traceutil/trace.go:171","msg":"trace[1296687492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1522; }","duration":"109.79637ms","start":"2026-05-19T18:00:19.959975Z","end":"2026-05-19T18:00:20.069772Z","steps":["trace[1296687492] 'agreement among raft nodes before linearized reading' (duration: 109.597085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:20.069768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.237557ms","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-05-19T18:00:20.069851Z","caller":"traceutil/trace.go:171","msg":"trace[110582979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1522; }","duration":"103.3485ms","start":"2026-05-19T18:00:19.966481Z","end":"2026-05-19T18:00:20.069829Z","steps":["trace[110582979] 'agreement among raft nodes before linearized reading' (duration: 103.102823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:20.070029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.465927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-19T18:00:20.070027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.146465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T18:00:20.070076Z","caller":"traceutil/trace.go:171","msg":"trace[1046330172] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1522; }","duration":"102.544569ms","start":"2026-05-19T18:00:19.967521Z","end":"2026-05-19T18:00:20.070065Z","steps":["trace[1046330172] 'agreement among raft nodes before linearized reading' (duration: 102.397675ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:00:20.070084Z","caller":"traceutil/trace.go:171","msg":"trace[994543693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1522; }","duration":"103.237367ms","start":"2026-05-19T18:00:19.966834Z","end":"2026-05-19T18:00:20.070071Z","steps":["trace[994543693] 'agreement among raft nodes before linearized reading' (duration: 103.096723ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:00:20.254712Z","caller":"traceutil/trace.go:171","msg":"trace[781380461] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"181.132805ms","start":"2026-05-19T18:00:20.073549Z","end":"2026-05-19T18:00:20.254682Z","steps":["trace[781380461] 'process raft request' (duration: 160.462898ms)","trace[781380461] 'compare' (duration: 20.535423ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:00:20.275239Z","caller":"traceutil/trace.go:171","msg":"trace[1556825776] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"201.120513ms","start":"2026-05-19T18:00:20.074092Z","end":"2026-05-19T18:00:20.275212Z","steps":["trace[1556825776] 'process raft request' (duration: 201.033431ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:00:20.275275Z","caller":"traceutil/trace.go:171","msg":"trace[1107823261] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"201.210116ms","start":"2026-05-19T18:00:20.074034Z","end":"2026-05-19T18:00:20.275244Z","steps":["trace[1107823261] 'process raft request' (duration: 200.942829ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:00:20.275413Z","caller":"traceutil/trace.go:171","msg":"trace[685737302] linearizableReadLoop","detail":"{readStateIndex:1589; appliedIndex:1585; }","duration":"122.447055ms","start":"2026-05-19T18:00:20.152932Z","end":"2026-05-19T18:00:20.275379Z","steps":["trace[685737302] 'read index received' (duration: 81.159684ms)","trace[685737302] 'applied index is now lower than readState.Index' (duration: 41.285391ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:00:20.275462Z","caller":"traceutil/trace.go:171","msg":"trace[1829295027] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"200.148657ms","start":"2026-05-19T18:00:20.075299Z","end":"2026-05-19T18:00:20.275448Z","steps":["trace[1829295027] 'process raft request' (duration: 199.87406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:20.275576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.63237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:00:20.27562Z","caller":"traceutil/trace.go:171","msg":"trace[128977665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1526; }","duration":"122.732002ms","start":"2026-05-19T18:00:20.152874Z","end":"2026-05-19T18:00:20.275606Z","steps":["trace[128977665] 'agreement among raft nodes before linearized reading' (duration: 122.64812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:21.5041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.005184ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T18:00:21.960209Z","caller":"traceutil/trace.go:171","msg":"trace[57565295] transaction","detail":"{read_only:false; response_revision:1529; number_of_response:1; }","duration":"334.803914ms","start":"2026-05-19T18:00:21.62537Z","end":"2026-05-19T18:00:21.960174Z","steps":["trace[57565295] 'process raft request' (duration: 334.591618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:21.960823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:00:21.625351Z","time spent":"335.3764ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:00:22.061023Z","caller":"traceutil/trace.go:171","msg":"trace[1755352090] transaction","detail":"{read_only:false; response_revision:1530; number_of_response:1; }","duration":"100.365182ms","start":"2026-05-19T18:00:21.960632Z","end":"2026-05-19T18:00:22.060997Z","steps":["trace[1755352090] 'process raft request' (duration: 95.567195ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:00:22.487054Z","caller":"traceutil/trace.go:171","msg":"trace[1021261492] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"157.356178ms","start":"2026-05-19T18:00:22.329672Z","end":"2026-05-19T18:00:22.487028Z","steps":["trace[1021261492] 'process raft request' (duration: 109.496794ms)","trace[1021261492] 'compare' (duration: 47.680909ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:00:22.487439Z","caller":"traceutil/trace.go:171","msg":"trace[244624] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"157.132601ms","start":"2026-05-19T18:00:22.330283Z","end":"2026-05-19T18:00:22.487416Z","steps":["trace[244624] 'process raft request' (duration: 156.69695ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:00:22.48753Z","caller":"traceutil/trace.go:171","msg":"trace[765010148] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"156.254198ms","start":"2026-05-19T18:00:22.331244Z","end":"2026-05-19T18:00:22.487498Z","steps":["trace[765010148] 'process raft request' (duration: 155.971161ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:00:24.661709Z","caller":"traceutil/trace.go:171","msg":"trace[1617190321] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"164.837805ms","start":"2026-05-19T18:00:24.496831Z","end":"2026-05-19T18:00:24.661668Z","steps":["trace[1617190321] 'process raft request' (duration: 98.783229ms)","trace[1617190321] 'compare' (duration: 65.720727ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:00:24.661887Z","caller":"traceutil/trace.go:171","msg":"trace[460810588] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"161.632401ms","start":"2026-05-19T18:00:24.500214Z","end":"2026-05-19T18:00:24.661847Z","steps":["trace[460810588] 'process raft request' (duration: 161.312282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:00:40.353526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.159514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:00:40.353662Z","caller":"traceutil/trace.go:171","msg":"trace[274663395] linearizableReadLoop","detail":"{readStateIndex:1680; appliedIndex:1679; }","duration":"139.778001ms","start":"2026-05-19T18:00:40.213864Z","end":"2026-05-19T18:00:40.353642Z","steps":["trace[274663395] 'read index received' (duration: 36.435182ms)","trace[274663395] 'applied index is now lower than readState.Index' (duration: 103.341739ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:00:40.353753Z","caller":"traceutil/trace.go:171","msg":"trace[838605303] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"229.534813ms","start":"2026-05-19T18:00:40.124209Z","end":"2026-05-19T18:00:40.353744Z","steps":["trace[838605303] 'process raft request' (duration: 126.080371ms)","trace[838605303] 'compare' (duration: 103.040111ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:00:40.354005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.125231ms","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-05-19T18:00:40.354081Z","caller":"traceutil/trace.go:171","msg":"trace[1801838564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1613; }","duration":"140.250965ms","start":"2026-05-19T18:00:40.213812Z","end":"2026-05-19T18:00:40.354063Z","steps":["trace[1801838564] 'agreement among raft nodes before linearized reading' (duration: 140.124202ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:00:40.521075Z","caller":"traceutil/trace.go:171","msg":"trace[1383615596] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"163.52916ms","start":"2026-05-19T18:00:40.357503Z","end":"2026-05-19T18:00:40.521032Z","steps":["trace[1383615596] 'process raft request' (duration: 113.528438ms)","trace[1383615596] 'compare' (duration: 49.765895ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:00:40.858004Z","caller":"traceutil/trace.go:171","msg":"trace[1451492244] linearizableReadLoop","detail":"{readStateIndex:1686; appliedIndex:1684; }","duration":"101.246504ms","start":"2026-05-19T18:00:40.756739Z","end":"2026-05-19T18:00:40.857986Z","steps":["trace[1451492244] 'read index received' (duration: 29.53382ms)","trace[1451492244] 'applied index is now lower than readState.Index' (duration: 71.712014ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:00:40.858163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.404079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-19T18:00:40.8582Z","caller":"traceutil/trace.go:171","msg":"trace[387495886] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1619; }","duration":"101.475382ms","start":"2026-05-19T18:00:40.756715Z","end":"2026-05-19T18:00:40.85819Z","steps":["trace[387495886] 'agreement among raft nodes before linearized reading' (duration: 101.341037ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:00:40.858272Z","caller":"traceutil/trace.go:171","msg":"trace[1802139457] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"105.281502ms","start":"2026-05-19T18:00:40.75291Z","end":"2026-05-19T18:00:40.858192Z","steps":["trace[1802139457] 'process raft request' (duration: 103.676929ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:01:10.411013Z","caller":"traceutil/trace.go:171","msg":"trace[32539675] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"140.580552ms","start":"2026-05-19T18:01:10.270394Z","end":"2026-05-19T18:01:10.410975Z","steps":["trace[32539675] 'process raft request' (duration: 140.344596ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:01:10.67086Z","caller":"traceutil/trace.go:171","msg":"trace[56283768] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"134.805631ms","start":"2026-05-19T18:01:10.535963Z","end":"2026-05-19T18:01:10.670769Z","steps":["trace[56283768] 'process raft request' (duration: 131.763491ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:01:12.275213Z","caller":"traceutil/trace.go:171","msg":"trace[150148575] transaction","detail":"{read_only:false; response_revision:1759; number_of_response:1; }","duration":"118.89238ms","start":"2026-05-19T18:01:12.156296Z","end":"2026-05-19T18:01:12.275189Z","steps":["trace[150148575] 'process raft request' (duration: 118.762497ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:01:21.306299Z","caller":"traceutil/trace.go:171","msg":"trace[767231297] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"158.629787ms","start":"2026-05-19T18:01:21.147617Z","end":"2026-05-19T18:01:21.306247Z","steps":["trace[767231297] 'process raft request' (duration: 84.921616ms)","trace[767231297] 'compare' (duration: 73.603048ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:01:27.857366Z","caller":"traceutil/trace.go:171","msg":"trace[1380488429] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"103.770343ms","start":"2026-05-19T18:01:27.753567Z","end":"2026-05-19T18:01:27.857337Z","steps":["trace[1380488429] 'process raft request' (duration: 103.553827ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:01:37.070592Z","caller":"traceutil/trace.go:171","msg":"trace[2107222293] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"116.622481ms","start":"2026-05-19T18:01:36.953935Z","end":"2026-05-19T18:01:37.070558Z","steps":["trace[2107222293] 'process raft request' (duration: 108.353394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:02:01.470464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.259669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:02:01.470578Z","caller":"traceutil/trace.go:171","msg":"trace[793147146] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2069; }","duration":"162.951141ms","start":"2026-05-19T18:02:01.30761Z","end":"2026-05-19T18:02:01.470561Z","steps":["trace[793147146] 'read index received' (duration: 59.23893ms)","trace[793147146] 'applied index is now lower than readState.Index' (duration: 103.710811ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:02:01.470714Z","caller":"traceutil/trace.go:171","msg":"trace[1712668253] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"254.332557ms","start":"2026-05-19T18:02:01.2163Z","end":"2026-05-19T18:02:01.470633Z","steps":["trace[1712668253] 'process raft request' (duration: 150.559174ms)","trace[1712668253] 'compare' (duration: 103.078755ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:02:01.470771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.172548ms","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-05-19T18:02:01.470853Z","caller":"traceutil/trace.go:171","msg":"trace[1599573407] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1987; }","duration":"163.291201ms","start":"2026-05-19T18:02:01.307546Z","end":"2026-05-19T18:02:01.470837Z","steps":["trace[1599573407] 'agreement among raft nodes before linearized reading' (duration: 163.123596ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:02:03.904083Z","caller":"traceutil/trace.go:171","msg":"trace[361616752] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"180.329689ms","start":"2026-05-19T18:02:03.723718Z","end":"2026-05-19T18:02:03.904047Z","steps":["trace[361616752] 'process raft request' (duration: 87.198516ms)","trace[361616752] 'compare' (duration: 92.978969ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:02:14.033715Z","caller":"traceutil/trace.go:171","msg":"trace[1698427654] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"110.274234ms","start":"2026-05-19T18:02:13.923418Z","end":"2026-05-19T18:02:14.033693Z","steps":["trace[1698427654] 'process raft request' (duration: 107.440319ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:02:14.076671Z","caller":"traceutil/trace.go:171","msg":"trace[1571795770] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"149.632611ms","start":"2026-05-19T18:02:13.927019Z","end":"2026-05-19T18:02:14.076652Z","steps":["trace[1571795770] 'process raft request' (duration: 149.517618ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:02:36.518605Z","caller":"traceutil/trace.go:171","msg":"trace[846412563] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"167.847846ms","start":"2026-05-19T18:02:36.350729Z","end":"2026-05-19T18:02:36.518577Z","steps":["trace[846412563] 'read index received' (duration: 167.60024ms)","trace[846412563] 'applied index is now lower than readState.Index' (duration: 246.486µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:02:36.51892Z","caller":"traceutil/trace.go:171","msg":"trace[1778681378] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"214.388876ms","start":"2026-05-19T18:02:36.304368Z","end":"2026-05-19T18:02:36.518757Z","steps":["trace[1778681378] 'process raft request' (duration: 214.053317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:02:36.518927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.172895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-19T18:02:36.519006Z","caller":"traceutil/trace.go:171","msg":"trace[333461116] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2146; }","duration":"168.29885ms","start":"2026-05-19T18:02:36.350687Z","end":"2026-05-19T18:02:36.518986Z","steps":["trace[333461116] 'agreement among raft nodes before linearized reading' (duration: 168.154076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:02:36.520297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.993248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-05-19T18:02:36.52043Z","caller":"traceutil/trace.go:171","msg":"trace[860257043] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:2146; }","duration":"111.180483ms","start":"2026-05-19T18:02:36.409215Z","end":"2026-05-19T18:02:36.520395Z","steps":["trace[860257043] 'agreement among raft nodes before linearized reading' (duration: 109.578421ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:03:00.414402Z","caller":"traceutil/trace.go:171","msg":"trace[673387971] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"128.001483ms","start":"2026-05-19T18:03:00.286371Z","end":"2026-05-19T18:03:00.414373Z","steps":["trace[673387971] 'process raft request' (duration: 119.298085ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:03:01.340922Z","caller":"traceutil/trace.go:171","msg":"trace[1443217241] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"100.688237ms","start":"2026-05-19T18:03:01.240152Z","end":"2026-05-19T18:03:01.34084Z","steps":["trace[1443217241] 'process raft request' (duration: 96.664612ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:03:01.983063Z","caller":"traceutil/trace.go:171","msg":"trace[1208631624] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"205.283127ms","start":"2026-05-19T18:03:01.777737Z","end":"2026-05-19T18:03:01.98302Z","steps":["trace[1208631624] 'process raft request' (duration: 159.868308ms)","trace[1208631624] 'compare' (duration: 45.248355ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:03:02.610666Z","caller":"traceutil/trace.go:171","msg":"trace[394103416] transaction","detail":"{read_only:false; response_revision:2265; number_of_response:1; }","duration":"140.217953ms","start":"2026-05-19T18:03:02.470416Z","end":"2026-05-19T18:03:02.610634Z","steps":["trace[394103416] 'process raft request' (duration: 139.885294ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:03:02.892708Z","caller":"traceutil/trace.go:171","msg":"trace[701848529] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"102.331051ms","start":"2026-05-19T18:03:02.790345Z","end":"2026-05-19T18:03:02.892676Z","steps":["trace[701848529] 'process raft request' (duration: 102.159486ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:03:20.494414Z","caller":"traceutil/trace.go:171","msg":"trace[1317777054] linearizableReadLoop","detail":"{readStateIndex:2468; appliedIndex:2467; }","duration":"417.403794ms","start":"2026-05-19T18:03:20.076985Z","end":"2026-05-19T18:03:20.494388Z","steps":["trace[1317777054] 'read index received' (duration: 417.188608ms)","trace[1317777054] 'applied index is now lower than readState.Index' (duration: 213.576µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:03:20.494621Z","caller":"traceutil/trace.go:171","msg":"trace[590691037] transaction","detail":"{read_only:false; response_revision:2368; number_of_response:1; }","duration":"430.725472ms","start":"2026-05-19T18:03:20.063784Z","end":"2026-05-19T18:03:20.494509Z","steps":["trace[590691037] 'process raft request' (duration: 430.379113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:03:20.494675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.66013ms","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-05-19T18:03:20.49473Z","caller":"traceutil/trace.go:171","msg":"trace[216749890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2368; }","duration":"417.765773ms","start":"2026-05-19T18:03:20.076948Z","end":"2026-05-19T18:03:20.494714Z","steps":["trace[216749890] 'agreement among raft nodes before linearized reading' (duration: 417.584698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:03:20.494775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:03:20.076932Z","time spent":"417.830184ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T18:03:20.494784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:03:20.063765Z","time spent":"430.940488ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:03:20.49516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.46408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-19T18:03:20.49524Z","caller":"traceutil/trace.go:171","msg":"trace[406399176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2369; }","duration":"119.598223ms","start":"2026-05-19T18:03:20.375621Z","end":"2026-05-19T18:03:20.495219Z","steps":["trace[406399176] 'agreement among raft nodes before linearized reading' (duration: 119.245493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:03:26.881497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.529699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-19T18:03:26.881557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.337562ms","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-05-19T18:03:26.881657Z","caller":"traceutil/trace.go:171","msg":"trace[1740600272] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2404; }","duration":"175.503257ms","start":"2026-05-19T18:03:26.706132Z","end":"2026-05-19T18:03:26.881635Z","steps":["trace[1740600272] 'count revisions from in-memory index tree' (duration: 175.24404ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:03:26.881592Z","caller":"traceutil/trace.go:171","msg":"trace[1774673406] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2404; }","duration":"172.672923ms","start":"2026-05-19T18:03:26.708898Z","end":"2026-05-19T18:03:26.881571Z","steps":["trace[1774673406] 'range keys from in-memory index tree' (duration: 172.321254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:03:26.8818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.523352ms","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-05-19T18:03:26.881896Z","caller":"traceutil/trace.go:171","msg":"trace[1745430633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2404; }","duration":"161.661976ms","start":"2026-05-19T18:03:26.720215Z","end":"2026-05-19T18:03:26.881877Z","steps":["trace[1745430633] 'range keys from in-memory index tree' (duration: 161.354158ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:03:27.085376Z","caller":"traceutil/trace.go:171","msg":"trace[1361679652] linearizableReadLoop","detail":"{readStateIndex:2508; appliedIndex:2506; }","duration":"187.368827ms","start":"2026-05-19T18:03:26.89798Z","end":"2026-05-19T18:03:27.085349Z","steps":["trace[1361679652] 'read index received' (duration: 55.108328ms)","trace[1361679652] 'applied index is now lower than readState.Index' (duration: 132.259579ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:03:27.085522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.518931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:03:27.08551Z","caller":"traceutil/trace.go:171","msg":"trace[1892705965] transaction","detail":"{read_only:false; response_revision:2406; number_of_response:1; }","duration":"198.837226ms","start":"2026-05-19T18:03:26.886635Z","end":"2026-05-19T18:03:27.085473Z","steps":["trace[1892705965] 'process raft request' (duration: 198.554219ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:03:27.085557Z","caller":"traceutil/trace.go:171","msg":"trace[1589018344] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2406; }","duration":"187.608623ms","start":"2026-05-19T18:03:26.897936Z","end":"2026-05-19T18:03:27.085545Z","steps":["trace[1589018344] 'agreement among raft nodes before linearized reading' (duration: 187.535521ms)"],"step_count":1}