{"level":"warn","ts":"2026-05-06T00:47:40.226516Z","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-06T00:47:40.226593Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.246: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.246:2380","--initial-cluster=instance=https://199.19.213.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.246: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-06T00:47:40.226737Z","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-06T00:47:40.226751Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.246:2380"]} {"level":"info","ts":"2026-05-06T00:47:40.226769Z","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-06T00:47:40.227366Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.246:2379"]} {"level":"info","ts":"2026-05-06T00:47:40.227437Z","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.246:2380"],"listen-peer-urls":["https://199.19.213.246:2380"],"advertise-client-urls":["https://199.19.213.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.246: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-06T00:47:40.231662Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.028669ms"} {"level":"info","ts":"2026-05-06T00:47:40.236918Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b4fc23d90360acd","cluster-id":"fbafe76a301d853c"} {"level":"info","ts":"2026-05-06T00:47:40.236985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T00:47:40.237008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became follower at term 0"} {"level":"info","ts":"2026-05-06T00:47:40.237024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b4fc23d90360acd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T00:47:40.237036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became follower at term 1"} {"level":"info","ts":"2026-05-06T00:47:40.237203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd switched to configuration voters=(3120926636459297485)"} {"level":"warn","ts":"2026-05-06T00:47:40.243415Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T00:47:40.244523Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T00:47:40.24691Z","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-06T00:47:40.250714Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b4fc23d90360acd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T00:47:40.250826Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b4fc23d90360acd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T00:47:40.250885Z","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-06T00:47:40.250971Z","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-06T00:47:40.250982Z","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-06T00:47:40.251614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd switched to configuration voters=(3120926636459297485)"} {"level":"info","ts":"2026-05-06T00:47:40.251722Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fbafe76a301d853c","local-member-id":"2b4fc23d90360acd","added-peer-id":"2b4fc23d90360acd","added-peer-peer-urls":["https://199.19.213.246:2380"]} {"level":"info","ts":"2026-05-06T00:47:40.258918Z","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-06T00:47:40.258981Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.246:2380"} {"level":"info","ts":"2026-05-06T00:47:40.25911Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.246:2380"} {"level":"info","ts":"2026-05-06T00:47:40.259298Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b4fc23d90360acd","initial-advertise-peer-urls":["https://199.19.213.246:2380"],"listen-peer-urls":["https://199.19.213.246:2380"],"advertise-client-urls":["https://199.19.213.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T00:47:40.259348Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T00:47:40.337563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T00:47:40.337629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T00:47:40.337719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd received MsgPreVoteResp from 2b4fc23d90360acd at term 1"} {"level":"info","ts":"2026-05-06T00:47:40.337815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became candidate at term 2"} {"level":"info","ts":"2026-05-06T00:47:40.337835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd received MsgVoteResp from 2b4fc23d90360acd at term 2"} {"level":"info","ts":"2026-05-06T00:47:40.337852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b4fc23d90360acd became leader at term 2"} {"level":"info","ts":"2026-05-06T00:47:40.33792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b4fc23d90360acd elected leader 2b4fc23d90360acd at term 2"} {"level":"info","ts":"2026-05-06T00:47:40.33966Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b4fc23d90360acd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.246:2379]}","request-path":"/0/members/2b4fc23d90360acd/attributes","cluster-id":"fbafe76a301d853c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T00:47:40.339942Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:47:40.339979Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:47:40.340149Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:47:40.342499Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.246:2379"} {"level":"info","ts":"2026-05-06T00:47:40.342684Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T00:47:40.342716Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T00:47:40.343008Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fbafe76a301d853c","local-member-id":"2b4fc23d90360acd","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:47:40.343147Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:47:40.343191Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:47:40.34442Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-06T00:47:44.81188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263484752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:47:44.811932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038999728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:47:44.811999Z","caller":"traceutil/trace.go:171","msg":"trace[1494521955] range","detail":"{range_begin:/registry/clusterroles/system:controller:root-ca-cert-publisher; range_end:; response_count:0; response_revision:133; }","duration":"1.263663274s","start":"2026-05-06T00:47:43.548313Z","end":"2026-05-06T00:47:44.811976Z","steps":["trace[1494521955] 'range keys from in-memory index tree' (duration: 1.263385589s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:44.812058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:43.548292Z","time spent":"1.263755777s","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" "} {"level":"info","ts":"2026-05-06T00:47:44.812049Z","caller":"traceutil/trace.go:171","msg":"trace[867529549] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:133; }","duration":"1.039157191s","start":"2026-05-06T00:47:43.772864Z","end":"2026-05-06T00:47:44.812022Z","steps":["trace[867529549] 'range keys from in-memory index tree' (duration: 1.038764954s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:44.812161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:43.771698Z","time spent":"1.040445547s","remote":"127.0.0.1:55716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-05-06T00:47:44.811932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.544815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:47:44.812335Z","caller":"traceutil/trace.go:171","msg":"trace[652439069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:133; }","duration":"785.980374ms","start":"2026-05-06T00:47:44.026333Z","end":"2026-05-06T00:47:44.812313Z","steps":["trace[652439069] 'range keys from in-memory index tree' (duration: 785.478194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:44.812506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:44.026313Z","time spent":"786.174678ms","remote":"127.0.0.1:55574","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-06T00:47:45.550454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":778452010914074209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:47:46.018547Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.202476603s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:47:46.019015Z","caller":"traceutil/trace.go:171","msg":"trace[894177096] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"1.203293618s","start":"2026-05-06T00:47:44.815693Z","end":"2026-05-06T00:47:46.018986Z","steps":["trace[894177096] 'process raft request' (duration: 1.203109875s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:46.019528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:44.815611Z","time spent":"1.203478412s","remote":"127.0.0.1:55776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:47:46.035322Z","caller":"traceutil/trace.go:171","msg":"trace[953815556] linearizableReadLoop","detail":"{readStateIndex:139; appliedIndex:137; }","duration":"985.329713ms","start":"2026-05-06T00:47:45.049964Z","end":"2026-05-06T00:47:46.035294Z","steps":["trace[953815556] 'read index received' (duration: 968.872129ms)","trace[953815556] 'applied index is now lower than readState.Index' (duration: 16.456384ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:47:46.035534Z","caller":"traceutil/trace.go:171","msg":"trace[56157414] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"1.219686512s","start":"2026-05-06T00:47:44.815836Z","end":"2026-05-06T00:47:46.035523Z","steps":["trace[56157414] 'process raft request' (duration: 1.217594501s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:46.035627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:44.81582Z","time spent":"1.219751253s","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":657,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:47:46.035844Z","caller":"traceutil/trace.go:171","msg":"trace[818394000] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"263.946699ms","start":"2026-05-06T00:47:45.771863Z","end":"2026-05-06T00:47:46.03581Z","steps":["trace[818394000] 'process raft request' (duration: 263.377828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:46.036074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.112519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:47:46.036256Z","caller":"traceutil/trace.go:171","msg":"trace[950179115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:136; }","duration":"986.286353ms","start":"2026-05-06T00:47:45.049951Z","end":"2026-05-06T00:47:46.036237Z","steps":["trace[950179115] 'agreement among raft nodes before linearized reading' (duration: 985.606259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:46.036008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.194686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:47:46.036368Z","caller":"traceutil/trace.go:171","msg":"trace[1653011640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:136; }","duration":"909.617145ms","start":"2026-05-06T00:47:45.126733Z","end":"2026-05-06T00:47:46.03635Z","steps":["trace[1653011640] 'agreement among raft nodes before linearized reading' (duration: 908.927391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:46.036456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:45.126687Z","time spent":"909.742638ms","remote":"127.0.0.1:55574","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-06T00:47:46.036434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:45.049895Z","time spent":"986.527008ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T00:47:47.273851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.551614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:47:47.274048Z","caller":"traceutil/trace.go:171","msg":"trace[217667475] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"154.946807ms","start":"2026-05-06T00:47:47.119071Z","end":"2026-05-06T00:47:47.274017Z","steps":["trace[217667475] 'process raft request' (duration: 16.77623ms)","trace[217667475] 'compare' (duration: 137.401661ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:20.436732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.795006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:20.436907Z","caller":"traceutil/trace.go:171","msg":"trace[79267087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"146.00247ms","start":"2026-05-06T00:48:20.290864Z","end":"2026-05-06T00:48:20.436866Z","steps":["trace[79267087] 'range keys from in-memory index tree' (duration: 145.661544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.437041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.248317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-05-06T00:48:20.437133Z","caller":"traceutil/trace.go:171","msg":"trace[227672244] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:524; }","duration":"318.340679ms","start":"2026-05-06T00:48:20.118771Z","end":"2026-05-06T00:48:20.437112Z","steps":["trace[227672244] 'range keys from in-memory index tree' (duration: 317.814079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.437189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:20.118758Z","time spent":"318.41452ms","remote":"127.0.0.1:55668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329971,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-05-06T00:48:20.43751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.866237ms","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-06T00:48:20.437652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.757046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-05-06T00:48:20.437714Z","caller":"traceutil/trace.go:171","msg":"trace[1350067225] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:524; }","duration":"145.846617ms","start":"2026-05-06T00:48:20.291851Z","end":"2026-05-06T00:48:20.437698Z","steps":["trace[1350067225] 'range keys from in-memory index tree' (duration: 144.726105ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:20.437621Z","caller":"traceutil/trace.go:171","msg":"trace[1654805107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"238.02726ms","start":"2026-05-06T00:48:20.199572Z","end":"2026-05-06T00:48:20.437599Z","steps":["trace[1654805107] 'range keys from in-memory index tree' (duration: 236.965249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.43802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.050851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-05-06T00:48:20.438111Z","caller":"traceutil/trace.go:171","msg":"trace[206050124] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:524; }","duration":"147.192073ms","start":"2026-05-06T00:48:20.290898Z","end":"2026-05-06T00:48:20.43809Z","steps":["trace[206050124] 'range keys from in-memory index tree' (duration: 145.595482ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:22.064641Z","caller":"traceutil/trace.go:171","msg":"trace[1361002747] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"243.492697ms","start":"2026-05-06T00:48:21.821139Z","end":"2026-05-06T00:48:22.064632Z","steps":["trace[1361002747] 'process raft request' (duration: 243.407276ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:22.064644Z","caller":"traceutil/trace.go:171","msg":"trace[1255575957] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"244.808724ms","start":"2026-05-06T00:48:21.819813Z","end":"2026-05-06T00:48:22.064621Z","steps":["trace[1255575957] 'process raft request' (duration: 244.696622ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:22.064638Z","caller":"traceutil/trace.go:171","msg":"trace[507135721] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"249.817543ms","start":"2026-05-06T00:48:21.81479Z","end":"2026-05-06T00:48:22.064607Z","steps":["trace[507135721] 'process raft request' (duration: 249.67817ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:22.064784Z","caller":"traceutil/trace.go:171","msg":"trace[956661166] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"237.00779ms","start":"2026-05-06T00:48:21.827768Z","end":"2026-05-06T00:48:22.064776Z","steps":["trace[956661166] 'process raft request' (duration: 236.885988ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:22.06484Z","caller":"traceutil/trace.go:171","msg":"trace[1503724565] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"233.054683ms","start":"2026-05-06T00:48:21.831779Z","end":"2026-05-06T00:48:22.064834Z","steps":["trace[1503724565] 'process raft request' (duration: 232.925561ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:22.064796Z","caller":"traceutil/trace.go:171","msg":"trace[1063872198] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"240.441148ms","start":"2026-05-06T00:48:21.824316Z","end":"2026-05-06T00:48:22.064758Z","steps":["trace[1063872198] 'process raft request' (duration: 240.269234ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:22.064859Z","caller":"traceutil/trace.go:171","msg":"trace[1772274458] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"212.357566ms","start":"2026-05-06T00:48:21.852491Z","end":"2026-05-06T00:48:22.064849Z","steps":["trace[1772274458] 'process raft request' (duration: 212.252354ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:22.064829Z","caller":"traceutil/trace.go:171","msg":"trace[958623462] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"239.376487ms","start":"2026-05-06T00:48:21.825397Z","end":"2026-05-06T00:48:22.064773Z","steps":["trace[958623462] 'process raft request' (duration: 239.222684ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:22.06496Z","caller":"traceutil/trace.go:171","msg":"trace[1487506968] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"212.410337ms","start":"2026-05-06T00:48:21.852544Z","end":"2026-05-06T00:48:22.064954Z","steps":["trace[1487506968] 'process raft request' (duration: 212.250354ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:22.065038Z","caller":"traceutil/trace.go:171","msg":"trace[245091875] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"183.07864ms","start":"2026-05-06T00:48:21.881953Z","end":"2026-05-06T00:48:22.065031Z","steps":["trace[245091875] 'process raft request' (duration: 182.892766ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:22.064588Z","caller":"traceutil/trace.go:171","msg":"trace[1377428059] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"253.669589ms","start":"2026-05-06T00:48:21.810891Z","end":"2026-05-06T00:48:22.06456Z","steps":["trace[1377428059] 'process raft request' (duration: 253.434303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:28.759281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.865689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:28.759366Z","caller":"traceutil/trace.go:171","msg":"trace[492866190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"468.986221ms","start":"2026-05-06T00:48:28.290359Z","end":"2026-05-06T00:48:28.759345Z","steps":["trace[492866190] 'range keys from in-memory index tree' (duration: 468.657285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:28.759403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:28.290342Z","time spent":"469.052083ms","remote":"127.0.0.1:55582","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-06T00:48:28.927843Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":778452010914075269,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:48:29.22974Z","caller":"traceutil/trace.go:171","msg":"trace[2042708733] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"890.393697ms","start":"2026-05-06T00:48:28.339307Z","end":"2026-05-06T00:48:29.2297Z","steps":["trace[2042708733] 'process raft request' (duration: 889.802426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:29.229958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:28.339283Z","time spent":"890.54846ms","remote":"127.0.0.1:55668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:29.237932Z","caller":"traceutil/trace.go:171","msg":"trace[948806175] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:658; }","duration":"810.979185ms","start":"2026-05-06T00:48:28.42693Z","end":"2026-05-06T00:48:29.237909Z","steps":["trace[948806175] 'read index received' (duration: 801.907438ms)","trace[948806175] 'applied index is now lower than readState.Index' (duration: 9.070567ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:29.23817Z","caller":"traceutil/trace.go:171","msg":"trace[125746511] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"485.559487ms","start":"2026-05-06T00:48:28.7526Z","end":"2026-05-06T00:48:29.238159Z","steps":["trace[125746511] 'process raft request' (duration: 485.19435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:29.238278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:28.752575Z","time spent":"485.626428ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:48:29.23847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.771329ms","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-06T00:48:29.238533Z","caller":"traceutil/trace.go:171","msg":"trace[1696737340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"725.877172ms","start":"2026-05-06T00:48:28.512641Z","end":"2026-05-06T00:48:29.238518Z","steps":["trace[1696737340] 'agreement among raft nodes before linearized reading' (duration: 725.703449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:29.238567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:28.512622Z","time spent":"725.938673ms","remote":"127.0.0.1:55844","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-06T00:48:29.238665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.743711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rr7n2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-06T00:48:29.238685Z","caller":"traceutil/trace.go:171","msg":"trace[1342290284] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rr7n2; range_end:; response_count:1; response_revision:640; }","duration":"811.804862ms","start":"2026-05-06T00:48:28.426875Z","end":"2026-05-06T00:48:29.23868Z","steps":["trace[1342290284] 'agreement among raft nodes before linearized reading' (duration: 811.766591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:29.238702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:28.426853Z","time spent":"811.845312ms","remote":"127.0.0.1:55776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-rr7n2\" "} {"level":"warn","ts":"2026-05-06T00:48:29.238804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.617908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:48:29.238875Z","caller":"traceutil/trace.go:171","msg":"trace[562454124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"724.71454ms","start":"2026-05-06T00:48:28.514126Z","end":"2026-05-06T00:48:29.23884Z","steps":["trace[562454124] 'agreement among raft nodes before linearized reading' (duration: 724.525496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:29.23893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:28.514071Z","time spent":"724.848992ms","remote":"127.0.0.1:55844","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-06T00:48:31.039113Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.739203695s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:48:31.120817Z","caller":"traceutil/trace.go:171","msg":"trace[742566597] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.814847903s","start":"2026-05-06T00:48:29.305788Z","end":"2026-05-06T00:48:31.120635Z","steps":["trace[742566597] 'process raft request' (duration: 1.814817623s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:31.120916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:29.30577Z","time spent":"1.815102359s","remote":"127.0.0.1:55816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:48:31.121277Z","caller":"traceutil/trace.go:171","msg":"trace[1693752991] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.875345852s","start":"2026-05-06T00:48:29.245918Z","end":"2026-05-06T00:48:31.121264Z","steps":["trace[1693752991] 'process raft request' (duration: 1.793462463s)","trace[1693752991] 'compare' (duration: 80.468242ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:31.121341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:29.245909Z","time spent":"1.875393203s","remote":"127.0.0.1:55844","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-06T00:48:31.121315Z","caller":"traceutil/trace.go:171","msg":"trace[1594779768] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:660; }","duration":"1.832953849s","start":"2026-05-06T00:48:29.288337Z","end":"2026-05-06T00:48:31.121291Z","steps":["trace[1594779768] 'read index received' (duration: 11.472336ms)","trace[1594779768] 'applied index is now lower than readState.Index' (duration: 1.821479783s)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:31.121542Z","caller":"traceutil/trace.go:171","msg":"trace[505907249] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.856614394s","start":"2026-05-06T00:48:29.264918Z","end":"2026-05-06T00:48:31.121533Z","steps":["trace[505907249] 'process raft request' (duration: 1.855601595s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:31.121625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:29.2649Z","time spent":"1.856664155s","remote":"127.0.0.1:55776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:48:31.121719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.833380327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:31.121739Z","caller":"traceutil/trace.go:171","msg":"trace[435683708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"1.833424997s","start":"2026-05-06T00:48:29.288307Z","end":"2026-05-06T00:48:31.121732Z","steps":["trace[435683708] 'agreement among raft nodes before linearized reading' (duration: 1.833390827s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:31.121761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:29.288293Z","time spent":"1.833464639s","remote":"127.0.0.1:55574","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-06T00:48:31.121811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.621128024s","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-06T00:48:31.121969Z","caller":"traceutil/trace.go:171","msg":"trace[1986718043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"1.621323768s","start":"2026-05-06T00:48:29.500636Z","end":"2026-05-06T00:48:31.12196Z","steps":["trace[1986718043] 'agreement among raft nodes before linearized reading' (duration: 1.621096382s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:31.122017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:29.500615Z","time spent":"1.621373509s","remote":"127.0.0.1:55844","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-06T00:48:31.122042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480898867s","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-06T00:48:31.122129Z","caller":"traceutil/trace.go:171","msg":"trace[1225512335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"1.481027709s","start":"2026-05-06T00:48:29.641081Z","end":"2026-05-06T00:48:31.122109Z","steps":["trace[1225512335] 'agreement among raft nodes before linearized reading' (duration: 1.480781435s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:31.122172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:29.641035Z","time spent":"1.481129251s","remote":"127.0.0.1:55844","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-06T00:48:32.96533Z","caller":"traceutil/trace.go:171","msg":"trace[522577345] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"338.630248ms","start":"2026-05-06T00:48:32.626677Z","end":"2026-05-06T00:48:32.965307Z","steps":["trace[522577345] 'process raft request' (duration: 338.497596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:32.96568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:32.626654Z","time spent":"338.829722ms","remote":"127.0.0.1:55630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:32.971647Z","caller":"traceutil/trace.go:171","msg":"trace[960713221] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"246.967287ms","start":"2026-05-06T00:48:32.724657Z","end":"2026-05-06T00:48:32.971624Z","steps":["trace[960713221] 'process raft request' (duration: 246.513007ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:33.892167Z","caller":"traceutil/trace.go:171","msg":"trace[351892132] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"165.393441ms","start":"2026-05-06T00:48:33.726748Z","end":"2026-05-06T00:48:33.892142Z","steps":["trace[351892132] 'process raft request' (duration: 165.266768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:34.325983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.879309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:48:34.326109Z","caller":"traceutil/trace.go:171","msg":"trace[341421446] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"383.053683ms","start":"2026-05-06T00:48:33.943038Z","end":"2026-05-06T00:48:34.326092Z","steps":["trace[341421446] 'process raft request' (duration: 201.98949ms)","trace[341421446] 'compare' (duration: 180.785548ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:34.326167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:33.943018Z","time spent":"383.122305ms","remote":"127.0.0.1:55630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:34.352259Z","caller":"traceutil/trace.go:171","msg":"trace[271669034] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:723; }","duration":"230.22617ms","start":"2026-05-06T00:48:34.122006Z","end":"2026-05-06T00:48:34.352232Z","steps":["trace[271669034] 'read index received' (duration: 23.001468ms)","trace[271669034] 'applied index is now lower than readState.Index' (duration: 207.223532ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:34.352311Z","caller":"traceutil/trace.go:171","msg":"trace[824842954] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"393.660769ms","start":"2026-05-06T00:48:33.958617Z","end":"2026-05-06T00:48:34.352277Z","steps":["trace[824842954] 'process raft request' (duration: 393.392374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:34.352484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.461024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:34.352538Z","caller":"traceutil/trace.go:171","msg":"trace[624985187] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:704; }","duration":"230.572086ms","start":"2026-05-06T00:48:34.121949Z","end":"2026-05-06T00:48:34.352521Z","steps":["trace[624985187] 'agreement among raft nodes before linearized reading' (duration: 230.416764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:34.352661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:33.958598Z","time spent":"393.813911ms","remote":"127.0.0.1:55630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:37.270759Z","caller":"traceutil/trace.go:171","msg":"trace[146487118] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"100.644798ms","start":"2026-05-06T00:48:37.170104Z","end":"2026-05-06T00:48:37.270749Z","steps":["trace[146487118] 'process raft request' (duration: 100.498276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:37.276269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.831821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:37.276627Z","caller":"traceutil/trace.go:171","msg":"trace[1966282363] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:738; }","duration":"102.219729ms","start":"2026-05-06T00:48:37.174391Z","end":"2026-05-06T00:48:37.27661Z","steps":["trace[1966282363] 'agreement among raft nodes before linearized reading' (duration: 96.380486ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:39.350458Z","caller":"traceutil/trace.go:171","msg":"trace[674705863] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"157.580437ms","start":"2026-05-06T00:48:39.192853Z","end":"2026-05-06T00:48:39.350433Z","steps":["trace[674705863] 'read index received' (duration: 157.379612ms)","trace[674705863] 'applied index is now lower than readState.Index' (duration: 199.605µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:39.350736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.429995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T00:48:39.350784Z","caller":"traceutil/trace.go:171","msg":"trace[2060122684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:754; }","duration":"155.517816ms","start":"2026-05-06T00:48:39.195251Z","end":"2026-05-06T00:48:39.350769Z","steps":["trace[2060122684] 'agreement among raft nodes before linearized reading' (duration: 155.338293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:39.351032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.249347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2075"} {"level":"info","ts":"2026-05-06T00:48:39.35113Z","caller":"traceutil/trace.go:171","msg":"trace[545937853] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:754; }","duration":"125.38517ms","start":"2026-05-06T00:48:39.225717Z","end":"2026-05-06T00:48:39.351102Z","steps":["trace[545937853] 'agreement among raft nodes before linearized reading' (duration: 125.116565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:39.351214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.337781ms","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-06T00:48:39.351307Z","caller":"traceutil/trace.go:171","msg":"trace[300133173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"158.495214ms","start":"2026-05-06T00:48:39.192791Z","end":"2026-05-06T00:48:39.351286Z","steps":["trace[300133173] 'agreement among raft nodes before linearized reading' (duration: 157.75324ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:39.351053Z","caller":"traceutil/trace.go:171","msg":"trace[1562702410] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"179.265937ms","start":"2026-05-06T00:48:39.171263Z","end":"2026-05-06T00:48:39.350528Z","steps":["trace[1562702410] 'process raft request' (duration: 179.005042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:46.618399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.742455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:46.618478Z","caller":"traceutil/trace.go:171","msg":"trace[22484594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"328.876968ms","start":"2026-05-06T00:48:46.289584Z","end":"2026-05-06T00:48:46.618461Z","steps":["trace[22484594] 'range keys from in-memory index tree' (duration: 328.666314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:46.618516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:46.289563Z","time spent":"328.94496ms","remote":"127.0.0.1:55574","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-06T00:48:47.120703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":778452010914075572,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:48:47.271947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:46.619776Z","time spent":"652.166529ms","remote":"127.0.0.1:55612","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-06T00:48:47.568565Z","caller":"traceutil/trace.go:171","msg":"trace[774909274] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"945.015917ms","start":"2026-05-06T00:48:46.623528Z","end":"2026-05-06T00:48:47.568544Z","steps":["trace[774909274] 'process raft request' (duration: 943.885485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:47.568711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:46.623505Z","time spent":"945.130409ms","remote":"127.0.0.1:55844","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-06T00:48:47.568729Z","caller":"traceutil/trace.go:171","msg":"trace[4227019] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"941.400336ms","start":"2026-05-06T00:48:46.627307Z","end":"2026-05-06T00:48:47.568707Z","steps":["trace[4227019] 'process raft request' (duration: 941.201143ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:47.56868Z","caller":"traceutil/trace.go:171","msg":"trace[1149947119] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:823; }","duration":"948.493254ms","start":"2026-05-06T00:48:46.62016Z","end":"2026-05-06T00:48:47.568653Z","steps":["trace[1149947119] 'read index received' (duration: 651.627678ms)","trace[1149947119] 'applied index is now lower than readState.Index' (duration: 296.864446ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:47.56887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:46.627279Z","time spent":"941.516759ms","remote":"127.0.0.1:55776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:48:47.568977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.80878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:47.569012Z","caller":"traceutil/trace.go:171","msg":"trace[144235384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"948.886152ms","start":"2026-05-06T00:48:46.620116Z","end":"2026-05-06T00:48:47.569002Z","steps":["trace[144235384] 'agreement among raft nodes before linearized reading' (duration: 948.80142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:47.569077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:46.620098Z","time spent":"948.939622ms","remote":"127.0.0.1:55582","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-06T00:48:47.572051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.797984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:48:47.572272Z","caller":"traceutil/trace.go:171","msg":"trace[367749622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"190.052008ms","start":"2026-05-06T00:48:47.38216Z","end":"2026-05-06T00:48:47.572212Z","steps":["trace[367749622] 'agreement among raft nodes before linearized reading' (duration: 189.566808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:47.57267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.55758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-06T00:48:47.572679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.191527ms","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-06T00:48:47.572745Z","caller":"traceutil/trace.go:171","msg":"trace[2033744450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"166.658232ms","start":"2026-05-06T00:48:47.406069Z","end":"2026-05-06T00:48:47.572727Z","steps":["trace[2033744450] 'agreement among raft nodes before linearized reading' (duration: 166.488459ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:47.572759Z","caller":"traceutil/trace.go:171","msg":"trace[1675520093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"104.312169ms","start":"2026-05-06T00:48:47.468428Z","end":"2026-05-06T00:48:47.57274Z","steps":["trace[1675520093] 'agreement among raft nodes before linearized reading' (duration: 104.065104ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:47.572768Z","caller":"traceutil/trace.go:171","msg":"trace[900372246] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"299.378055ms","start":"2026-05-06T00:48:47.273376Z","end":"2026-05-06T00:48:47.572754Z","steps":["trace[900372246] 'process raft request' (duration: 298.138571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:47.573529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.775831ms","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-06T00:48:47.57358Z","caller":"traceutil/trace.go:171","msg":"trace[175271431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"100.859802ms","start":"2026-05-06T00:48:47.472708Z","end":"2026-05-06T00:48:47.573568Z","steps":["trace[175271431] 'agreement among raft nodes before linearized reading' (duration: 99.698809ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:17.816418Z","caller":"traceutil/trace.go:171","msg":"trace[1422520641] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"105.949332ms","start":"2026-05-06T00:49:17.710452Z","end":"2026-05-06T00:49:17.816401Z","steps":["trace[1422520641] 'process raft request' (duration: 105.702866ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:24.063731Z","caller":"traceutil/trace.go:171","msg":"trace[497349528] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"189.07471ms","start":"2026-05-06T00:49:23.874633Z","end":"2026-05-06T00:49:24.063708Z","steps":["trace[497349528] 'process raft request' (duration: 188.889605ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:24.063919Z","caller":"traceutil/trace.go:171","msg":"trace[847142891] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"188.537689ms","start":"2026-05-06T00:49:23.87537Z","end":"2026-05-06T00:49:24.063908Z","steps":["trace[847142891] 'process raft request' (duration: 188.291373ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:24.586404Z","caller":"traceutil/trace.go:171","msg":"trace[1203950096] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"296.055331ms","start":"2026-05-06T00:49:24.290325Z","end":"2026-05-06T00:49:24.58638Z","steps":["trace[1203950096] 'read index received' (duration: 295.849087ms)","trace[1203950096] 'applied index is now lower than readState.Index' (duration: 205.154µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:24.58655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.200523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:24.586605Z","caller":"traceutil/trace.go:171","msg":"trace[637741644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1028; }","duration":"296.299475ms","start":"2026-05-06T00:49:24.290293Z","end":"2026-05-06T00:49:24.586593Z","steps":["trace[637741644] 'agreement among raft nodes before linearized reading' (duration: 296.186813ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:24.58697Z","caller":"traceutil/trace.go:171","msg":"trace[1439069533] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"427.510218ms","start":"2026-05-06T00:49:24.159345Z","end":"2026-05-06T00:49:24.586855Z","steps":["trace[1439069533] 'process raft request' (duration: 426.891876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:24.58713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:24.159323Z","time spent":"427.733371ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:25.20485Z","caller":"traceutil/trace.go:171","msg":"trace[717782537] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"286.673058ms","start":"2026-05-06T00:49:24.918145Z","end":"2026-05-06T00:49:25.204818Z","steps":["trace[717782537] 'process raft request' (duration: 286.474173ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:31.98235Z","caller":"traceutil/trace.go:171","msg":"trace[254996208] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"102.031696ms","start":"2026-05-06T00:49:31.880296Z","end":"2026-05-06T00:49:31.982328Z","steps":["trace[254996208] 'read index received' (duration: 43.590948ms)","trace[254996208] 'applied index is now lower than readState.Index' (duration: 58.439158ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:31.982603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.28452ms","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-06T00:49:31.982709Z","caller":"traceutil/trace.go:171","msg":"trace[1621415141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1049; }","duration":"102.441203ms","start":"2026-05-06T00:49:31.880254Z","end":"2026-05-06T00:49:31.982695Z","steps":["trace[1621415141] 'agreement among raft nodes before linearized reading' (duration: 102.172198ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:32.131537Z","caller":"traceutil/trace.go:171","msg":"trace[769080135] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"145.991041ms","start":"2026-05-06T00:49:31.985527Z","end":"2026-05-06T00:49:32.131518Z","steps":["trace[769080135] 'process raft request' (duration: 145.864459ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:32.146479Z","caller":"traceutil/trace.go:171","msg":"trace[2072106573] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"159.651677ms","start":"2026-05-06T00:49:31.986796Z","end":"2026-05-06T00:49:32.146447Z","steps":["trace[2072106573] 'process raft request' (duration: 159.552025ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:32.146457Z","caller":"traceutil/trace.go:171","msg":"trace[160668646] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"160.157896ms","start":"2026-05-06T00:49:31.986268Z","end":"2026-05-06T00:49:32.146426Z","steps":["trace[160668646] 'process raft request' (duration: 159.881711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:46.813651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":778452010914076236,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:49:47.017504Z","caller":"traceutil/trace.go:171","msg":"trace[86546245] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"704.075963ms","start":"2026-05-06T00:49:46.31341Z","end":"2026-05-06T00:49:47.017486Z","steps":["trace[86546245] 'read index received' (duration: 703.95035ms)","trace[86546245] 'applied index is now lower than readState.Index' (duration: 124.933µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:47.017587Z","caller":"traceutil/trace.go:171","msg":"trace[248167522] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"710.098404ms","start":"2026-05-06T00:49:46.307471Z","end":"2026-05-06T00:49:47.017569Z","steps":["trace[248167522] 'process raft request' (duration: 709.92629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:47.017691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:46.307445Z","time spent":"710.182045ms","remote":"127.0.0.1:55844","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-06T00:49:47.017683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.264467ms","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-06T00:49:47.017814Z","caller":"traceutil/trace.go:171","msg":"trace[461393214] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1096; }","duration":"704.376599ms","start":"2026-05-06T00:49:46.313378Z","end":"2026-05-06T00:49:47.017755Z","steps":["trace[461393214] 'agreement among raft nodes before linearized reading' (duration: 704.201646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:47.017855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:46.313366Z","time spent":"704.478731ms","remote":"127.0.0.1:55844","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-06T00:49:47.017869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.713759ms","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-06T00:49:47.017897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.76443ms","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-06T00:49:47.01783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.309763ms","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-06T00:49:47.017899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.393819ms","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-06T00:49:47.017936Z","caller":"traceutil/trace.go:171","msg":"trace[1236007194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1096; }","duration":"677.845602ms","start":"2026-05-06T00:49:46.340081Z","end":"2026-05-06T00:49:47.017926Z","steps":["trace[1236007194] 'agreement among raft nodes before linearized reading' (duration: 677.724489ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:47.017941Z","caller":"traceutil/trace.go:171","msg":"trace[1753068010] 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:1096; }","duration":"201.478497ms","start":"2026-05-06T00:49:46.816453Z","end":"2026-05-06T00:49:47.017931Z","steps":["trace[1753068010] 'agreement among raft nodes before linearized reading' (duration: 201.329614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:47.017971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:46.340065Z","time spent":"677.899142ms","remote":"127.0.0.1:55844","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-06T00:49:47.017976Z","caller":"traceutil/trace.go:171","msg":"trace[2143842420] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1096; }","duration":"350.565633ms","start":"2026-05-06T00:49:46.667392Z","end":"2026-05-06T00:49:47.017957Z","steps":["trace[2143842420] 'agreement among raft nodes before linearized reading' (duration: 350.38966ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:47.017932Z","caller":"traceutil/trace.go:171","msg":"trace[2111921423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1096; }","duration":"677.803601ms","start":"2026-05-06T00:49:46.340111Z","end":"2026-05-06T00:49:47.017914Z","steps":["trace[2111921423] 'agreement among raft nodes before linearized reading' (duration: 677.667948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:47.018024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:46.667371Z","time spent":"350.643264ms","remote":"127.0.0.1:38576","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-06T00:49:47.018072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:46.3401Z","time spent":"677.936993ms","remote":"127.0.0.1:55844","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-06T00:49:47.018045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.790703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-06T00:49:47.0182Z","caller":"traceutil/trace.go:171","msg":"trace[249899575] range","detail":"{range_begin:/registry/masterleases/199.19.213.246; range_end:; response_count:1; response_revision:1096; }","duration":"484.001606ms","start":"2026-05-06T00:49:46.534152Z","end":"2026-05-06T00:49:47.018154Z","steps":["trace[249899575] 'agreement among raft nodes before linearized reading' (duration: 483.621629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:47.018237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.002895ms","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-06T00:49:47.018257Z","caller":"traceutil/trace.go:171","msg":"trace[1026283327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1096; }","duration":"679.517125ms","start":"2026-05-06T00:49:46.338735Z","end":"2026-05-06T00:49:47.018253Z","steps":["trace[1026283327] 'agreement among raft nodes before linearized reading' (duration: 678.913913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:47.018271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:46.338714Z","time spent":"679.553805ms","remote":"127.0.0.1:55844","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-06T00:49:47.018286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:46.534134Z","time spent":"484.108149ms","remote":"127.0.0.1:55612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.246\" "} {"level":"info","ts":"2026-05-06T00:49:49.541372Z","caller":"traceutil/trace.go:171","msg":"trace[407577804] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"485.513327ms","start":"2026-05-06T00:49:49.055834Z","end":"2026-05-06T00:49:49.541347Z","steps":["trace[407577804] 'read index received' (duration: 485.270852ms)","trace[407577804] 'applied index is now lower than readState.Index' (duration: 241.315µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:49.541494Z","caller":"traceutil/trace.go:171","msg":"trace[1360885356] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"514.817929ms","start":"2026-05-06T00:49:49.026634Z","end":"2026-05-06T00:49:49.541451Z","steps":["trace[1360885356] 'process raft request' (duration: 514.548624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:49.541678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:49.026617Z","time spent":"514.962122ms","remote":"127.0.0.1:55844","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-06T00:49:49.541678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.810812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:49:49.541794Z","caller":"traceutil/trace.go:171","msg":"trace[611014717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"485.973505ms","start":"2026-05-06T00:49:49.055801Z","end":"2026-05-06T00:49:49.541774Z","steps":["trace[611014717] 'agreement among raft nodes before linearized reading' (duration: 485.678799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:49.541847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:49.055789Z","time spent":"486.045146ms","remote":"127.0.0.1:55844","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-06T00:49:50.042405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":778452010914076259,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:49:50.313752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.386258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:49:50.314238Z","caller":"traceutil/trace.go:171","msg":"trace[1851589394] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"1.021778172s","start":"2026-05-06T00:49:49.292432Z","end":"2026-05-06T00:49:50.31421Z","steps":["trace[1851589394] 'process raft request' (duration: 753.820813ms)","trace[1851589394] 'compare' (duration: 266.963139ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:50.314388Z","caller":"traceutil/trace.go:171","msg":"trace[1545326683] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1141; }","duration":"772.914332ms","start":"2026-05-06T00:49:49.541449Z","end":"2026-05-06T00:49:50.314364Z","steps":["trace[1545326683] 'read index received' (duration: 504.811831ms)","trace[1545326683] 'applied index is now lower than readState.Index' (duration: 268.099771ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:50.314441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024564888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:50.314466Z","caller":"traceutil/trace.go:171","msg":"trace[157489482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1105; }","duration":"1.024652529s","start":"2026-05-06T00:49:49.289806Z","end":"2026-05-06T00:49:50.314458Z","steps":["trace[157489482] 'agreement among raft nodes before linearized reading' (duration: 1.024609628s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:50.314503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:49.289786Z","time spent":"1.02471356s","remote":"127.0.0.1:55574","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-06T00:49:50.314531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257571371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-06T00:49:50.314555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258618533s","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-06T00:49:50.314564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258135583s","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-06T00:49:50.314589Z","caller":"traceutil/trace.go:171","msg":"trace[991389897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1105; }","duration":"1.258699304s","start":"2026-05-06T00:49:49.05588Z","end":"2026-05-06T00:49:50.31458Z","steps":["trace[991389897] 'agreement among raft nodes before linearized reading' (duration: 1.258579582s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:50.314588Z","caller":"traceutil/trace.go:171","msg":"trace[113192579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1105; }","duration":"1.257690573s","start":"2026-05-06T00:49:49.056882Z","end":"2026-05-06T00:49:50.314572Z","steps":["trace[113192579] 'agreement among raft nodes before linearized reading' (duration: 1.25750939s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:50.314624Z","caller":"traceutil/trace.go:171","msg":"trace[810235748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1105; }","duration":"1.258233414s","start":"2026-05-06T00:49:49.05638Z","end":"2026-05-06T00:49:50.314614Z","steps":["trace[810235748] 'agreement among raft nodes before linearized reading' (duration: 1.258045971s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:50.314633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:49.05586Z","time spent":"1.258764825s","remote":"127.0.0.1:55844","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-06T00:49:50.314635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:49.056848Z","time spent":"1.257779165s","remote":"127.0.0.1:55844","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-06T00:49:50.314656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:49.056369Z","time spent":"1.258279925s","remote":"127.0.0.1:55844","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-06T00:49:50.314381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:49.292409Z","time spent":"1.021892204s","remote":"127.0.0.1:55844","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-06T00:49:50.314409Z","caller":"traceutil/trace.go:171","msg":"trace[127539778] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"767.846932ms","start":"2026-05-06T00:49:49.546537Z","end":"2026-05-06T00:49:50.314384Z","steps":["trace[127539778] 'process raft request' (duration: 767.323332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:50.315077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:49.546512Z","time spent":"768.517475ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:50.45469Z","caller":"traceutil/trace.go:171","msg":"trace[984432341] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"134.679009ms","start":"2026-05-06T00:49:50.319957Z","end":"2026-05-06T00:49:50.454636Z","steps":["trace[984432341] 'process raft request' (duration: 93.417729ms)","trace[984432341] 'compare' (duration: 40.800881ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:50.455369Z","caller":"traceutil/trace.go:171","msg":"trace[1005454774] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"135.120408ms","start":"2026-05-06T00:49:50.320217Z","end":"2026-05-06T00:49:50.455337Z","steps":["trace[1005454774] 'process raft request' (duration: 134.933465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:50.455607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.834652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:50.455819Z","caller":"traceutil/trace.go:171","msg":"trace[1783864425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1108; }","duration":"132.136067ms","start":"2026-05-06T00:49:50.323631Z","end":"2026-05-06T00:49:50.455767Z","steps":["trace[1783864425] 'agreement among raft nodes before linearized reading' (duration: 131.785681ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:50.456799Z","caller":"traceutil/trace.go:171","msg":"trace[2090570440] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1143; }","duration":"131.556487ms","start":"2026-05-06T00:49:50.323694Z","end":"2026-05-06T00:49:50.455251Z","steps":["trace[2090570440] 'read index received' (duration: 23.62965ms)","trace[2090570440] 'applied index is now lower than readState.Index' (duration: 107.925237ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:50.912677Z","caller":"traceutil/trace.go:171","msg":"trace[255783188] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"105.941256ms","start":"2026-05-06T00:49:50.806707Z","end":"2026-05-06T00:49:50.912648Z","steps":["trace[255783188] 'read index received' (duration: 105.784353ms)","trace[255783188] 'applied index is now lower than readState.Index' (duration: 155.233µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:50.912709Z","caller":"traceutil/trace.go:171","msg":"trace[332757402] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"120.815574ms","start":"2026-05-06T00:49:50.791871Z","end":"2026-05-06T00:49:50.912686Z","steps":["trace[332757402] 'process raft request' (duration: 120.590908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:50.912812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.08465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:49:50.91288Z","caller":"traceutil/trace.go:171","msg":"trace[1530432204] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1109; }","duration":"106.194332ms","start":"2026-05-06T00:49:50.806678Z","end":"2026-05-06T00:49:50.912872Z","steps":["trace[1530432204] 'agreement among raft nodes before linearized reading' (duration: 106.07645ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:51.937186Z","caller":"traceutil/trace.go:171","msg":"trace[2132442324] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"386.584679ms","start":"2026-05-06T00:49:51.550575Z","end":"2026-05-06T00:49:51.93716Z","steps":["trace[2132442324] 'process raft request' (duration: 386.310573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:51.937532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:51.550555Z","time spent":"386.70911ms","remote":"127.0.0.1:55844","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-06T00:49:52.219751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.569072ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T00:50:02.702914Z","caller":"traceutil/trace.go:171","msg":"trace[31558868] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"185.646801ms","start":"2026-05-06T00:50:02.517234Z","end":"2026-05-06T00:50:02.702881Z","steps":["trace[31558868] 'process raft request' (duration: 91.593451ms)","trace[31558868] 'compare' (duration: 93.814236ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:02.872586Z","caller":"traceutil/trace.go:171","msg":"trace[886008243] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"184.282164ms","start":"2026-05-06T00:50:02.688283Z","end":"2026-05-06T00:50:02.872565Z","steps":["trace[886008243] 'read index received' (duration: 116.352982ms)","trace[886008243] 'applied index is now lower than readState.Index' (duration: 67.928302ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:02.872727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.409367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:02.872772Z","caller":"traceutil/trace.go:171","msg":"trace[1815534772] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1199; }","duration":"184.537959ms","start":"2026-05-06T00:50:02.688225Z","end":"2026-05-06T00:50:02.872763Z","steps":["trace[1815534772] 'agreement among raft nodes before linearized reading' (duration: 184.415187ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:02.87279Z","caller":"traceutil/trace.go:171","msg":"trace[464625732] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"355.288036ms","start":"2026-05-06T00:50:02.517442Z","end":"2026-05-06T00:50:02.87273Z","steps":["trace[464625732] 'process raft request' (duration: 287.272852ms)","trace[464625732] 'compare' (duration: 67.754098ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:02.872975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:02.517375Z","time spent":"355.501141ms","remote":"127.0.0.1:55844","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-06T00:50:04.766127Z","caller":"traceutil/trace.go:171","msg":"trace[546603398] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"476.708542ms","start":"2026-05-06T00:50:04.289388Z","end":"2026-05-06T00:50:04.766097Z","steps":["trace[546603398] 'read index received' (duration: 476.56375ms)","trace[546603398] 'applied index is now lower than readState.Index' (duration: 143.362µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:04.766166Z","caller":"traceutil/trace.go:171","msg":"trace[917052760] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"778.84661ms","start":"2026-05-06T00:50:03.987288Z","end":"2026-05-06T00:50:04.766135Z","steps":["trace[917052760] 'process raft request' (duration: 778.642166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:04.766271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.854035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:04.766334Z","caller":"traceutil/trace.go:171","msg":"trace[1932146779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1200; }","duration":"476.958458ms","start":"2026-05-06T00:50:04.28936Z","end":"2026-05-06T00:50:04.766318Z","steps":["trace[1932146779] 'agreement among raft nodes before linearized reading' (duration: 476.842805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:04.766377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:04.289347Z","time spent":"477.021639ms","remote":"127.0.0.1:55574","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-06T00:50:04.766365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:03.987272Z","time spent":"779.007353ms","remote":"127.0.0.1:55844","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-06T00:50:05.267134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":778452010914076464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:50:05.373989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.063645ms","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-06T00:50:05.374112Z","caller":"traceutil/trace.go:171","msg":"trace[1401007180] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1200; }","duration":"489.201828ms","start":"2026-05-06T00:50:04.884894Z","end":"2026-05-06T00:50:05.374096Z","steps":["trace[1401007180] 'range keys from in-memory index tree' (duration: 489.050075ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:05.374532Z","caller":"traceutil/trace.go:171","msg":"trace[1316542381] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1243; }","duration":"608.310907ms","start":"2026-05-06T00:50:04.766209Z","end":"2026-05-06T00:50:05.37452Z","steps":["trace[1316542381] 'read index received' (duration: 606.395919ms)","trace[1316542381] 'applied index is now lower than readState.Index' (duration: 1.914138ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:05.374615Z","caller":"traceutil/trace.go:171","msg":"trace[1584674795] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"896.776245ms","start":"2026-05-06T00:50:04.477808Z","end":"2026-05-06T00:50:05.374585Z","steps":["trace[1584674795] 'process raft request' (duration: 894.856618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:05.374711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.409074ms","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-06T00:50:05.374739Z","caller":"traceutil/trace.go:171","msg":"trace[933019877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1201; }","duration":"668.508186ms","start":"2026-05-06T00:50:04.706222Z","end":"2026-05-06T00:50:05.37473Z","steps":["trace[933019877] 'agreement among raft nodes before linearized reading' (duration: 668.371263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:05.374759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:04.477788Z","time spent":"896.890529ms","remote":"127.0.0.1:55844","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-06T00:50:05.374775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:04.7062Z","time spent":"668.563217ms","remote":"127.0.0.1:55844","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-06T00:50:05.374835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.736207ms","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-06T00:50:05.37488Z","caller":"traceutil/trace.go:171","msg":"trace[1929679130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1201; }","duration":"893.803118ms","start":"2026-05-06T00:50:04.481065Z","end":"2026-05-06T00:50:05.374868Z","steps":["trace[1929679130] 'agreement among raft nodes before linearized reading' (duration: 893.673775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:05.374912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:04.481059Z","time spent":"893.846319ms","remote":"127.0.0.1:55844","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-06T00:50:05.375059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.511172ms","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-06T00:50:05.37507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.268668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:05.375088Z","caller":"traceutil/trace.go:171","msg":"trace[1362491931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1201; }","duration":"887.548353ms","start":"2026-05-06T00:50:04.487531Z","end":"2026-05-06T00:50:05.375079Z","steps":["trace[1362491931] 'agreement among raft nodes before linearized reading' (duration: 887.469481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:05.375113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:04.487526Z","time spent":"887.580953ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T00:50:05.375107Z","caller":"traceutil/trace.go:171","msg":"trace[348736109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"607.330159ms","start":"2026-05-06T00:50:04.767763Z","end":"2026-05-06T00:50:05.375094Z","steps":["trace[348736109] 'agreement among raft nodes before linearized reading' (duration: 607.284178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:05.375163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:04.767747Z","time spent":"607.40173ms","remote":"127.0.0.1:55582","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-06T00:50:05.375356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.896741ms","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-06T00:50:05.375413Z","caller":"traceutil/trace.go:171","msg":"trace[1682791900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1201; }","duration":"495.023655ms","start":"2026-05-06T00:50:04.880376Z","end":"2026-05-06T00:50:05.3754Z","steps":["trace[1682791900] 'agreement among raft nodes before linearized reading' (duration: 494.821461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:05.375453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:04.880355Z","time spent":"495.089546ms","remote":"127.0.0.1:55844","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-06T00:50:18.492431Z","caller":"traceutil/trace.go:171","msg":"trace[2074270370] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"127.652229ms","start":"2026-05-06T00:50:18.36476Z","end":"2026-05-06T00:50:18.492412Z","steps":["trace[2074270370] 'process raft request' (duration: 127.548347ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:18.49279Z","caller":"traceutil/trace.go:171","msg":"trace[966252141] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1295; }","duration":"114.958876ms","start":"2026-05-06T00:50:18.377823Z","end":"2026-05-06T00:50:18.492782Z","steps":["trace[966252141] 'read index received' (duration: 114.952936ms)","trace[966252141] 'applied index is now lower than readState.Index' (duration: 5.36µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:18.492867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.027847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:18.492891Z","caller":"traceutil/trace.go:171","msg":"trace[1575354570] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1249; }","duration":"115.098379ms","start":"2026-05-06T00:50:18.377787Z","end":"2026-05-06T00:50:18.492885Z","steps":["trace[1575354570] 'agreement among raft nodes before linearized reading' (duration: 115.027108ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:20.580396Z","caller":"traceutil/trace.go:171","msg":"trace[1821459006] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"101.433638ms","start":"2026-05-06T00:50:20.478938Z","end":"2026-05-06T00:50:20.580372Z","steps":["trace[1821459006] 'process raft request' (duration: 101.295395ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:20.740146Z","caller":"traceutil/trace.go:171","msg":"trace[303288848] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"153.034254ms","start":"2026-05-06T00:50:20.587082Z","end":"2026-05-06T00:50:20.740116Z","steps":["trace[303288848] 'process raft request' (duration: 87.436159ms)","trace[303288848] 'compare' (duration: 65.481433ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:21.603504Z","caller":"traceutil/trace.go:171","msg":"trace[175318775] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"126.054688ms","start":"2026-05-06T00:50:21.477421Z","end":"2026-05-06T00:50:21.603476Z","steps":["trace[175318775] 'process raft request' (duration: 125.854764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:22.10388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":778452010914076622,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:50:22.459865Z","caller":"traceutil/trace.go:171","msg":"trace[1745193912] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"874.575665ms","start":"2026-05-06T00:50:21.585239Z","end":"2026-05-06T00:50:22.459815Z","steps":["trace[1745193912] 'process raft request' (duration: 874.064635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:22.460018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:21.58522Z","time spent":"874.714818ms","remote":"127.0.0.1:55844","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-06T00:50:22.656793Z","caller":"traceutil/trace.go:171","msg":"trace[1220214827] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"1.049114696s","start":"2026-05-06T00:50:21.607647Z","end":"2026-05-06T00:50:22.656762Z","steps":["trace[1220214827] 'process raft request' (duration: 973.895249ms)","trace[1220214827] 'compare' (duration: 75.054924ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:22.656965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:21.607628Z","time spent":"1.049240579s","remote":"127.0.0.1:55844","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-06T00:50:22.751393Z","caller":"traceutil/trace.go:171","msg":"trace[1923518570] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1320; }","duration":"1.147973862s","start":"2026-05-06T00:50:21.603395Z","end":"2026-05-06T00:50:22.751369Z","steps":["trace[1923518570] 'read index received' (duration: 855.966575ms)","trace[1923518570] 'applied index is now lower than readState.Index' (duration: 292.006207ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:22.751539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24390153s","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-06T00:50:22.751539Z","caller":"traceutil/trace.go:171","msg":"trace[1982140705] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"590.983414ms","start":"2026-05-06T00:50:22.160534Z","end":"2026-05-06T00:50:22.751518Z","steps":["trace[1982140705] 'process raft request' (duration: 590.663318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:22.751569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243229637s","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-06T00:50:22.751596Z","caller":"traceutil/trace.go:171","msg":"trace[1679305685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"1.243996021s","start":"2026-05-06T00:50:21.507586Z","end":"2026-05-06T00:50:22.751582Z","steps":["trace[1679305685] 'agreement among raft nodes before linearized reading' (duration: 1.243831938s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:22.751639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:21.507575Z","time spent":"1.244055703s","remote":"127.0.0.1:55844","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-06T00:50:22.751649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:22.160511Z","time spent":"591.081936ms","remote":"127.0.0.1:55762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5640,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:50:22.751647Z","caller":"traceutil/trace.go:171","msg":"trace[1533488902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1277; }","duration":"1.243349669s","start":"2026-05-06T00:50:21.50828Z","end":"2026-05-06T00:50:22.75163Z","steps":["trace[1533488902] 'agreement among raft nodes before linearized reading' (duration: 1.243126405s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:22.751663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134914341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T00:50:22.751689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:21.508268Z","time spent":"1.24341248s","remote":"127.0.0.1:55844","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-06T00:50:22.751715Z","caller":"traceutil/trace.go:171","msg":"trace[459646442] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1277; }","duration":"1.134991493s","start":"2026-05-06T00:50:21.616712Z","end":"2026-05-06T00:50:22.751704Z","steps":["trace[459646442] 'agreement among raft nodes before linearized reading' (duration: 1.134918682s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:22.751747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:21.616702Z","time spent":"1.135036415s","remote":"127.0.0.1:43228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"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-06T00:50:22.7516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247584013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:50:22.751806Z","caller":"traceutil/trace.go:171","msg":"trace[1131423502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"1.247830879s","start":"2026-05-06T00:50:21.503965Z","end":"2026-05-06T00:50:22.751796Z","steps":["trace[1131423502] 'agreement among raft nodes before linearized reading' (duration: 1.247524352s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:22.751834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:21.503946Z","time spent":"1.2478802s","remote":"127.0.0.1:55844","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-06T00:50:22.751843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.470813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:50:22.751867Z","caller":"traceutil/trace.go:171","msg":"trace[328228613] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1277; }","duration":"506.518444ms","start":"2026-05-06T00:50:22.245341Z","end":"2026-05-06T00:50:22.751859Z","steps":["trace[328228613] 'agreement among raft nodes before linearized reading' (duration: 506.476073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:22.751882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:22.245329Z","time spent":"506.549375ms","remote":"127.0.0.1:55836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:50:22.751926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089130521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:22.751956Z","caller":"traceutil/trace.go:171","msg":"trace[1942955054] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1277; }","duration":"1.089161342s","start":"2026-05-06T00:50:21.662786Z","end":"2026-05-06T00:50:22.751948Z","steps":["trace[1942955054] 'agreement among raft nodes before linearized reading' (duration: 1.089116061s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:22.751983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:21.662751Z","time spent":"1.089225824s","remote":"127.0.0.1:55716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:50:22.861267Z","caller":"traceutil/trace.go:171","msg":"trace[1399098015] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"104.861836ms","start":"2026-05-06T00:50:22.756381Z","end":"2026-05-06T00:50:22.861243Z","steps":["trace[1399098015] 'process raft request' (duration: 50.195698ms)","trace[1399098015] 'compare' (duration: 54.466844ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:22.892309Z","caller":"traceutil/trace.go:171","msg":"trace[1662490959] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"135.550036ms","start":"2026-05-06T00:50:22.756736Z","end":"2026-05-06T00:50:22.892286Z","steps":["trace[1662490959] 'process raft request' (duration: 135.410903ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:22.892556Z","caller":"traceutil/trace.go:171","msg":"trace[434907405] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"135.684619ms","start":"2026-05-06T00:50:22.756864Z","end":"2026-05-06T00:50:22.892548Z","steps":["trace[434907405] 'process raft request' (duration: 135.393143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:23.868291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.581207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:23.868341Z","caller":"traceutil/trace.go:171","msg":"trace[693762987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"578.664829ms","start":"2026-05-06T00:50:23.289665Z","end":"2026-05-06T00:50:23.86833Z","steps":["trace[693762987] 'range keys from in-memory index tree' (duration: 578.442515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:23.868362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:23.289651Z","time spent":"578.7074ms","remote":"127.0.0.1:55574","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-06T00:50:24.198115Z","caller":"traceutil/trace.go:171","msg":"trace[184711182] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"377.0506ms","start":"2026-05-06T00:50:23.821036Z","end":"2026-05-06T00:50:24.198087Z","steps":["trace[184711182] 'read index received' (duration: 376.918936ms)","trace[184711182] 'applied index is now lower than readState.Index' (duration: 130.434µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:24.198254Z","caller":"traceutil/trace.go:171","msg":"trace[495267538] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"590.390702ms","start":"2026-05-06T00:50:23.607846Z","end":"2026-05-06T00:50:24.198237Z","steps":["trace[495267538] 'process raft request' (duration: 590.078655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:24.198372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.164636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:24.198468Z","caller":"traceutil/trace.go:171","msg":"trace[63826557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"329.284699ms","start":"2026-05-06T00:50:23.869168Z","end":"2026-05-06T00:50:24.198453Z","steps":["trace[63826557] 'agreement among raft nodes before linearized reading' (duration: 329.160636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:24.19851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:23.869154Z","time spent":"329.34522ms","remote":"127.0.0.1:55582","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-06T00:50:24.198508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.472417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T00:50:24.198379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:23.607822Z","time spent":"590.468903ms","remote":"127.0.0.1:55844","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-06T00:50:24.19856Z","caller":"traceutil/trace.go:171","msg":"trace[1517572086] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1283; }","duration":"377.592829ms","start":"2026-05-06T00:50:23.820954Z","end":"2026-05-06T00:50:24.198547Z","steps":["trace[1517572086] 'agreement among raft nodes before linearized reading' (duration: 377.502958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:24.19866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:23.820914Z","time spent":"377.729872ms","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:50:24.199189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.728949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:24.199304Z","caller":"traceutil/trace.go:171","msg":"trace[1292851272] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1283; }","duration":"130.895112ms","start":"2026-05-06T00:50:24.068388Z","end":"2026-05-06T00:50:24.199283Z","steps":["trace[1292851272] 'agreement among raft nodes before linearized reading' (duration: 130.135317ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:25.015885Z","caller":"traceutil/trace.go:171","msg":"trace[1435881072] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"119.712421ms","start":"2026-05-06T00:50:24.896143Z","end":"2026-05-06T00:50:25.015855Z","steps":["trace[1435881072] 'read index received' (duration: 119.487766ms)","trace[1435881072] 'applied index is now lower than readState.Index' (duration: 223.425µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:25.015998Z","caller":"traceutil/trace.go:171","msg":"trace[962598871] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"145.745459ms","start":"2026-05-06T00:50:24.870219Z","end":"2026-05-06T00:50:25.015965Z","steps":["trace[962598871] 'process raft request' (duration: 145.476483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:25.01613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.950646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:50:25.016162Z","caller":"traceutil/trace.go:171","msg":"trace[821693582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"120.047708ms","start":"2026-05-06T00:50:24.896105Z","end":"2026-05-06T00:50:25.016153Z","steps":["trace[821693582] 'agreement among raft nodes before linearized reading' (duration: 119.867384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:25.032309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.558246ms","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-06T00:50:25.032417Z","caller":"traceutil/trace.go:171","msg":"trace[1292756584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1286; }","duration":"135.705229ms","start":"2026-05-06T00:50:24.896695Z","end":"2026-05-06T00:50:25.0324Z","steps":["trace[1292756584] 'agreement among raft nodes before linearized reading' (duration: 135.415814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:25.032436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.051699ms","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-06T00:50:25.0325Z","caller":"traceutil/trace.go:171","msg":"trace[87056111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1286; }","duration":"104.150621ms","start":"2026-05-06T00:50:24.928336Z","end":"2026-05-06T00:50:25.032487Z","steps":["trace[87056111] 'agreement among raft nodes before linearized reading' (duration: 103.950227ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:25.159984Z","caller":"traceutil/trace.go:171","msg":"trace[754123531] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1333; }","duration":"121.112139ms","start":"2026-05-06T00:50:25.038848Z","end":"2026-05-06T00:50:25.15996Z","steps":["trace[754123531] 'read index received' (duration: 28.247272ms)","trace[754123531] 'applied index is now lower than readState.Index' (duration: 92.863777ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:25.160012Z","caller":"traceutil/trace.go:171","msg":"trace[563869119] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"121.251112ms","start":"2026-05-06T00:50:25.038733Z","end":"2026-05-06T00:50:25.159984Z","steps":["trace[563869119] 'process raft request' (duration: 71.93043ms)","trace[563869119] 'compare' (duration: 49.112247ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:25.160197Z","caller":"traceutil/trace.go:171","msg":"trace[837107261] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"121.293853ms","start":"2026-05-06T00:50:25.038887Z","end":"2026-05-06T00:50:25.160181Z","steps":["trace[837107261] 'process raft request' (duration: 121.022317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:25.160223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.349894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:25.160268Z","caller":"traceutil/trace.go:171","msg":"trace[1856625453] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1289; }","duration":"121.428585ms","start":"2026-05-06T00:50:25.038829Z","end":"2026-05-06T00:50:25.160258Z","steps":["trace[1856625453] 'agreement among raft nodes before linearized reading' (duration: 121.240342ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:25.208179Z","caller":"traceutil/trace.go:171","msg":"trace[8391868] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"106.683281ms","start":"2026-05-06T00:50:25.101471Z","end":"2026-05-06T00:50:25.208154Z","steps":["trace[8391868] 'process raft request' (duration: 106.558599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:25.512959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.049047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:50:25.513068Z","caller":"traceutil/trace.go:171","msg":"trace[188070990] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1340; }","duration":"220.390383ms","start":"2026-05-06T00:50:25.292644Z","end":"2026-05-06T00:50:25.513035Z","steps":["trace[188070990] 'read index received' (duration: 82.221µs)","trace[188070990] 'applied index is now lower than readState.Index' (duration: 220.306932ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:25.513193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.508076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:25.513247Z","caller":"traceutil/trace.go:171","msg":"trace[1201384086] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"249.485302ms","start":"2026-05-06T00:50:25.263729Z","end":"2026-05-06T00:50:25.513214Z","steps":["trace[1201384086] 'process raft request' (duration: 29.067068ms)","trace[1201384086] 'compare' (duration: 219.585807ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:25.513296Z","caller":"traceutil/trace.go:171","msg":"trace[1923058722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"220.70183ms","start":"2026-05-06T00:50:25.292583Z","end":"2026-05-06T00:50:25.513285Z","steps":["trace[1923058722] 'agreement among raft nodes before linearized reading' (duration: 220.506706ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:26.048539Z","caller":"traceutil/trace.go:171","msg":"trace[353552092] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"477.018028ms","start":"2026-05-06T00:50:25.571497Z","end":"2026-05-06T00:50:26.048515Z","steps":["trace[353552092] 'process raft request' (duration: 476.872635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:26.048685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:25.571472Z","time spent":"477.13841ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:50:26.04902Z","caller":"traceutil/trace.go:171","msg":"trace[364094286] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1342; }","duration":"328.412641ms","start":"2026-05-06T00:50:25.720582Z","end":"2026-05-06T00:50:26.048995Z","steps":["trace[364094286] 'read index received' (duration: 328.402261ms)","trace[364094286] 'applied index is now lower than readState.Index' (duration: 6.04µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:26.049176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.571055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:50:26.049218Z","caller":"traceutil/trace.go:171","msg":"trace[935796937] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1295; }","duration":"328.663937ms","start":"2026-05-06T00:50:25.720542Z","end":"2026-05-06T00:50:26.049206Z","steps":["trace[935796937] 'agreement among raft nodes before linearized reading' (duration: 328.568786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:26.04926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:25.720522Z","time spent":"328.728328ms","remote":"127.0.0.1:36390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:50:26.452052Z","caller":"traceutil/trace.go:171","msg":"trace[1181594550] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"246.744877ms","start":"2026-05-06T00:50:26.205288Z","end":"2026-05-06T00:50:26.452032Z","steps":["trace[1181594550] 'process raft request' (duration: 246.626875ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:34.741867Z","caller":"traceutil/trace.go:171","msg":"trace[734360652] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"286.931356ms","start":"2026-05-06T00:50:34.454912Z","end":"2026-05-06T00:50:34.741843Z","steps":["trace[734360652] 'process raft request' (duration: 286.789114ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:34.765268Z","caller":"traceutil/trace.go:171","msg":"trace[2122885479] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1407; }","duration":"214.244532ms","start":"2026-05-06T00:50:34.551003Z","end":"2026-05-06T00:50:34.765247Z","steps":["trace[2122885479] 'read index received' (duration: 190.710123ms)","trace[2122885479] 'applied index is now lower than readState.Index' (duration: 23.533319ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:34.765419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.387544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:34.765464Z","caller":"traceutil/trace.go:171","msg":"trace[846935190] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1360; }","duration":"214.495576ms","start":"2026-05-06T00:50:34.550953Z","end":"2026-05-06T00:50:34.765449Z","steps":["trace[846935190] 'agreement among raft nodes before linearized reading' (duration: 214.400425ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:34.765558Z","caller":"traceutil/trace.go:171","msg":"trace[1839885801] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"286.906987ms","start":"2026-05-06T00:50:34.478464Z","end":"2026-05-06T00:50:34.765371Z","steps":["trace[1839885801] 'process raft request' (duration: 286.653772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:35.645919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":778452010914076789,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:50:35.753706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.143277ms","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-06T00:50:35.753934Z","caller":"traceutil/trace.go:171","msg":"trace[1341676752] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1360; }","duration":"869.392491ms","start":"2026-05-06T00:50:34.884521Z","end":"2026-05-06T00:50:35.753914Z","steps":["trace[1341676752] 'range keys from in-memory index tree' (duration: 869.125805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:35.945272Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.175599632s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:50:35.94575Z","caller":"traceutil/trace.go:171","msg":"trace[1062456146] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"800.842417ms","start":"2026-05-06T00:50:35.144878Z","end":"2026-05-06T00:50:35.945721Z","steps":["trace[1062456146] 'read index received' (duration: 800.618593ms)","trace[1062456146] 'applied index is now lower than readState.Index' (duration: 222.844µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:35.945975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.099513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:50:35.946057Z","caller":"traceutil/trace.go:171","msg":"trace[352304248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1361; }","duration":"801.228815ms","start":"2026-05-06T00:50:35.144804Z","end":"2026-05-06T00:50:35.946033Z","steps":["trace[352304248] 'agreement among raft nodes before linearized reading' (duration: 801.066742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:35.946125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:35.144762Z","time spent":"801.345958ms","remote":"127.0.0.1:55844","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-06T00:50:35.946207Z","caller":"traceutil/trace.go:171","msg":"trace[1008114205] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"1.176423697s","start":"2026-05-06T00:50:34.769554Z","end":"2026-05-06T00:50:35.945978Z","steps":["trace[1008114205] 'process raft request' (duration: 1.176013839s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:35.946611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.624212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-06T00:50:35.946701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.037739ms","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-06T00:50:35.946749Z","caller":"traceutil/trace.go:171","msg":"trace[283589781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1361; }","duration":"796.818838ms","start":"2026-05-06T00:50:35.149895Z","end":"2026-05-06T00:50:35.946714Z","steps":["trace[283589781] 'agreement among raft nodes before linearized reading' (duration: 796.244716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:35.946808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.598949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:35.946831Z","caller":"traceutil/trace.go:171","msg":"trace[520682136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"656.660309ms","start":"2026-05-06T00:50:35.290165Z","end":"2026-05-06T00:50:35.946826Z","steps":["trace[520682136] 'agreement among raft nodes before linearized reading' (duration: 656.627889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:35.946831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:35.149876Z","time spent":"796.94372ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T00:50:35.946862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:35.290145Z","time spent":"656.711231ms","remote":"127.0.0.1:55574","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-06T00:50:35.946647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:34.76953Z","time spent":"1.176954418s","remote":"127.0.0.1:55844","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-06T00:50:35.946769Z","caller":"traceutil/trace.go:171","msg":"trace[2102939969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1361; }","duration":"744.156431ms","start":"2026-05-06T00:50:35.202597Z","end":"2026-05-06T00:50:35.946754Z","steps":["trace[2102939969] 'agreement among raft nodes before linearized reading' (duration: 743.950876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:35.94703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.131769ms","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-06T00:50:35.947066Z","caller":"traceutil/trace.go:171","msg":"trace[1199460637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1361; }","duration":"745.223521ms","start":"2026-05-06T00:50:35.201832Z","end":"2026-05-06T00:50:35.947055Z","steps":["trace[1199460637] 'agreement among raft nodes before linearized reading' (duration: 745.142549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:35.947037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.913321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-05-06T00:50:35.947033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:35.202576Z","time spent":"744.438216ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T00:50:35.947094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:35.2018Z","time spent":"745.286863ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T00:50:35.947286Z","caller":"traceutil/trace.go:171","msg":"trace[1435076083] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1361; }","duration":"602.451691ms","start":"2026-05-06T00:50:35.344669Z","end":"2026-05-06T00:50:35.947121Z","steps":["trace[1435076083] 'agreement among raft nodes before linearized reading' (duration: 601.524622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:35.948518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:35.344643Z","time spent":"603.8606ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-06T00:50:35.946902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.32022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-06T00:50:35.949147Z","caller":"traceutil/trace.go:171","msg":"trace[1475327782] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1361; }","duration":"423.554554ms","start":"2026-05-06T00:50:35.525523Z","end":"2026-05-06T00:50:35.949077Z","steps":["trace[1475327782] 'agreement among raft nodes before linearized reading' (duration: 421.183257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:35.949525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:35.525503Z","time spent":"423.722587ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-06T00:51:06.664346Z","caller":"traceutil/trace.go:171","msg":"trace[908335235] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"108.748276ms","start":"2026-05-06T00:51:06.55556Z","end":"2026-05-06T00:51:06.664308Z","steps":["trace[908335235] 'process raft request' (duration: 68.63113ms)","trace[908335235] 'compare' (duration: 39.993204ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:51:40.47963Z","caller":"traceutil/trace.go:171","msg":"trace[256558635] linearizableReadLoop","detail":"{readStateIndex:1721; appliedIndex:1719; }","duration":"113.892018ms","start":"2026-05-06T00:51:40.365695Z","end":"2026-05-06T00:51:40.479587Z","steps":["trace[256558635] 'read index received' (duration: 2.236584ms)","trace[256558635] 'applied index is now lower than readState.Index' (duration: 111.654044ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:51:40.479672Z","caller":"traceutil/trace.go:171","msg":"trace[1983393532] transaction","detail":"{read_only:false; response_revision:1659; number_of_response:1; }","duration":"156.386151ms","start":"2026-05-06T00:51:40.323253Z","end":"2026-05-06T00:51:40.479639Z","steps":["trace[1983393532] 'process raft request' (duration: 102.89608ms)","trace[1983393532] 'compare' (duration: 53.243036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:51:40.479935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.232454ms","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-06T00:51:40.480022Z","caller":"traceutil/trace.go:171","msg":"trace[148907212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1659; }","duration":"114.386017ms","start":"2026-05-06T00:51:40.365619Z","end":"2026-05-06T00:51:40.480005Z","steps":["trace[148907212] 'agreement among raft nodes before linearized reading' (duration: 114.101631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:51:40.480085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.542242ms","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-06T00:51:40.480148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.952859ms","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-06T00:51:40.480204Z","caller":"traceutil/trace.go:171","msg":"trace[1048141313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1659; }","duration":"105.730697ms","start":"2026-05-06T00:51:40.374447Z","end":"2026-05-06T00:51:40.480177Z","steps":["trace[1048141313] 'agreement among raft nodes before linearized reading' (duration: 105.43499ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:40.48023Z","caller":"traceutil/trace.go:171","msg":"trace[1240169329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1659; }","duration":"108.098144ms","start":"2026-05-06T00:51:40.372117Z","end":"2026-05-06T00:51:40.480215Z","steps":["trace[1240169329] 'agreement among raft nodes before linearized reading' (duration: 107.707255ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:47.644111Z","caller":"traceutil/trace.go:171","msg":"trace[374330258] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"103.070865ms","start":"2026-05-06T00:52:47.541016Z","end":"2026-05-06T00:52:47.644087Z","steps":["trace[374330258] 'process raft request' (duration: 65.302906ms)","trace[374330258] 'compare' (duration: 37.668147ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:53:56.87703Z","caller":"traceutil/trace.go:171","msg":"trace[821635830] linearizableReadLoop","detail":"{readStateIndex:2402; appliedIndex:2401; }","duration":"402.677486ms","start":"2026-05-06T00:53:56.474321Z","end":"2026-05-06T00:53:56.876999Z","steps":["trace[821635830] 'read index received' (duration: 402.3582ms)","trace[821635830] 'applied index is now lower than readState.Index' (duration: 317.696µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:53:56.877142Z","caller":"traceutil/trace.go:171","msg":"trace[1545471289] transaction","detail":"{read_only:false; response_revision:2312; number_of_response:1; }","duration":"444.965824ms","start":"2026-05-06T00:53:56.432143Z","end":"2026-05-06T00:53:56.877109Z","steps":["trace[1545471289] 'process raft request' (duration: 444.637447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:53:56.877593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.927166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-06T00:53:56.877693Z","caller":"traceutil/trace.go:171","msg":"trace[1888847323] range","detail":"{range_begin:/registry/masterleases/199.19.213.246; range_end:; response_count:1; response_revision:2312; }","duration":"327.105739ms","start":"2026-05-06T00:53:56.550572Z","end":"2026-05-06T00:53:56.877677Z","steps":["trace[1888847323] 'agreement among raft nodes before linearized reading' (duration: 326.848724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:53:56.877781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:53:56.550541Z","time spent":"327.228642ms","remote":"127.0.0.1:55612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.246\" "} {"level":"warn","ts":"2026-05-06T00:53:56.878104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:53:56.432126Z","time spent":"445.224229ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:53:56.878311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.096315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-06T00:53:56.878775Z","caller":"traceutil/trace.go:171","msg":"trace[672718283] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2312; }","duration":"404.222438ms","start":"2026-05-06T00:53:56.474229Z","end":"2026-05-06T00:53:56.878452Z","steps":["trace[672718283] 'agreement among raft nodes before linearized reading' (duration: 402.995784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:53:56.878861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:53:56.474191Z","time spent":"404.649256ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "}