{"level":"warn","ts":"2026-05-29T14:02:46.220624Z","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-29T14:02:46.22071Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.63: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.63:2380","--initial-cluster=instance=https://199.19.213.63:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.63:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.63: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-29T14:02:46.220778Z","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-29T14:02:46.220792Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.63:2380"]} {"level":"info","ts":"2026-05-29T14:02:46.220837Z","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-29T14:02:46.221437Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.63:2379"]} {"level":"info","ts":"2026-05-29T14:02:46.221529Z","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.63:2380"],"listen-peer-urls":["https://199.19.213.63:2380"],"advertise-client-urls":["https://199.19.213.63:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.63:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.63: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-29T14:02:46.22569Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.893433ms"} {"level":"info","ts":"2026-05-29T14:02:46.23521Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d75c480e593e7aed","cluster-id":"24fce67309ccbdc"} {"level":"info","ts":"2026-05-29T14:02:46.235318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T14:02:46.235348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became follower at term 0"} {"level":"info","ts":"2026-05-29T14:02:46.235364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d75c480e593e7aed [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T14:02:46.235381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became follower at term 1"} {"level":"info","ts":"2026-05-29T14:02:46.235469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed switched to configuration voters=(15518357642475895533)"} {"level":"warn","ts":"2026-05-29T14:02:46.247931Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T14:02:46.249604Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T14:02:46.250727Z","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-29T14:02:46.255028Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d75c480e593e7aed","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T14:02:46.255271Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d75c480e593e7aed","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T14:02:46.255565Z","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-29T14:02:46.25839Z","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-29T14:02:46.258418Z","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-29T14:02:46.259051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed switched to configuration voters=(15518357642475895533)"} {"level":"info","ts":"2026-05-29T14:02:46.259859Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"24fce67309ccbdc","local-member-id":"d75c480e593e7aed","added-peer-id":"d75c480e593e7aed","added-peer-peer-urls":["https://199.19.213.63:2380"]} {"level":"info","ts":"2026-05-29T14:02:46.26298Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.63:2380"} {"level":"info","ts":"2026-05-29T14:02:46.262936Z","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-29T14:02:46.263353Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T14:02:46.26335Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d75c480e593e7aed","initial-advertise-peer-urls":["https://199.19.213.63:2380"],"listen-peer-urls":["https://199.19.213.63:2380"],"advertise-client-urls":["https://199.19.213.63:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.63:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T14:02:46.263007Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.63:2380"} {"level":"info","ts":"2026-05-29T14:02:46.636009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T14:02:46.636183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T14:02:46.636253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed received MsgPreVoteResp from d75c480e593e7aed at term 1"} {"level":"info","ts":"2026-05-29T14:02:46.636306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became candidate at term 2"} {"level":"info","ts":"2026-05-29T14:02:46.636369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed received MsgVoteResp from d75c480e593e7aed at term 2"} {"level":"info","ts":"2026-05-29T14:02:46.636387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d75c480e593e7aed became leader at term 2"} {"level":"info","ts":"2026-05-29T14:02:46.636432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d75c480e593e7aed elected leader d75c480e593e7aed at term 2"} {"level":"info","ts":"2026-05-29T14:02:46.637464Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T14:02:46.638194Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d75c480e593e7aed","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.63:2379]}","request-path":"/0/members/d75c480e593e7aed/attributes","cluster-id":"24fce67309ccbdc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T14:02:46.63824Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T14:02:46.638223Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T14:02:46.638545Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T14:02:46.638617Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T14:02:46.638666Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"24fce67309ccbdc","local-member-id":"d75c480e593e7aed","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T14:02:46.638795Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T14:02:46.638823Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T14:02:46.640458Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T14:02:46.645333Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.63:2379"} {"level":"info","ts":"2026-05-29T14:02:51.484111Z","caller":"traceutil/trace.go:171","msg":"trace[502555407] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:239; }","duration":"485.351357ms","start":"2026-05-29T14:02:50.998733Z","end":"2026-05-29T14:02:51.484084Z","steps":["trace[502555407] 'read index received' (duration: 482.755424ms)","trace[502555407] 'applied index is now lower than readState.Index' (duration: 2.594982ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:02:51.484216Z","caller":"traceutil/trace.go:171","msg":"trace[2007383308] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"486.605347ms","start":"2026-05-29T14:02:50.997581Z","end":"2026-05-29T14:02:51.484187Z","steps":["trace[2007383308] 'process raft request' (duration: 483.764387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:51.48432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.558693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-29T14:02:51.484443Z","caller":"traceutil/trace.go:171","msg":"trace[1334765276] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:234; }","duration":"485.715268ms","start":"2026-05-29T14:02:50.998715Z","end":"2026-05-29T14:02:51.48443Z","steps":["trace[1334765276] 'agreement among raft nodes before linearized reading' (duration: 485.515052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:51.484557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:50.998709Z","time spent":"485.788581ms","remote":"127.0.0.1:51450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-29T14:02:51.484944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:50.997566Z","time spent":"486.73095ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T14:02:51.488053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.821541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:02:51.488273Z","caller":"traceutil/trace.go:171","msg":"trace[503271905] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"161.909763ms","start":"2026-05-29T14:02:51.326126Z","end":"2026-05-29T14:02:51.488036Z","steps":["trace[503271905] 'process raft request' (duration: 161.80689ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:02:51.488808Z","caller":"traceutil/trace.go:171","msg":"trace[879522723] transaction","detail":"{read_only:false; number_of_response:0; response_revision:238; }","duration":"162.376518ms","start":"2026-05-29T14:02:51.32641Z","end":"2026-05-29T14:02:51.488787Z","steps":["trace[879522723] 'process raft request' (duration: 161.559882ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:02:51.489607Z","caller":"traceutil/trace.go:171","msg":"trace[351350190] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"163.616397ms","start":"2026-05-29T14:02:51.325333Z","end":"2026-05-29T14:02:51.488949Z","steps":["trace[351350190] 'process raft request' (duration: 162.566493ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:02:51.489615Z","caller":"traceutil/trace.go:171","msg":"trace[719392145] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:238; }","duration":"361.940984ms","start":"2026-05-29T14:02:51.126164Z","end":"2026-05-29T14:02:51.488105Z","steps":["trace[719392145] 'agreement among raft nodes before linearized reading' (duration: 361.835581ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:02:51.488461Z","caller":"traceutil/trace.go:171","msg":"trace[1118307705] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"162.073388ms","start":"2026-05-29T14:02:51.326344Z","end":"2026-05-29T14:02:51.488417Z","steps":["trace[1118307705] 'process raft request' (duration: 161.612403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:51.490216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:51.126141Z","time spent":"364.047611ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"info","ts":"2026-05-29T14:02:51.488114Z","caller":"traceutil/trace.go:171","msg":"trace[1752569857] transaction","detail":"{read_only:false; number_of_response:0; response_revision:235; }","duration":"185.574954ms","start":"2026-05-29T14:02:51.302519Z","end":"2026-05-29T14:02:51.488094Z","steps":["trace[1752569857] 'process raft request' (duration: 185.354527ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:02:51.491154Z","caller":"traceutil/trace.go:171","msg":"trace[1930779721] transaction","detail":"{read_only:false; number_of_response:0; response_revision:238; }","duration":"161.579823ms","start":"2026-05-29T14:02:51.326542Z","end":"2026-05-29T14:02:51.488122Z","steps":["trace[1930779721] 'process raft request' (duration: 161.441588ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:02:51.492509Z","caller":"traceutil/trace.go:171","msg":"trace[873807231] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"280.288565ms","start":"2026-05-29T14:02:51.212191Z","end":"2026-05-29T14:02:51.492479Z","steps":["trace[873807231] 'process raft request' (duration: 275.588857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:51.492725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.497682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-29T14:02:51.492784Z","caller":"traceutil/trace.go:171","msg":"trace[170707837] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:238; }","duration":"280.552583ms","start":"2026-05-29T14:02:51.212207Z","end":"2026-05-29T14:02:51.492759Z","steps":["trace[170707837] 'agreement among raft nodes before linearized reading' (duration: 280.44827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:51.492998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.819492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:02:51.493375Z","caller":"traceutil/trace.go:171","msg":"trace[363338780] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:238; }","duration":"281.187294ms","start":"2026-05-29T14:02:51.212141Z","end":"2026-05-29T14:02:51.493328Z","steps":["trace[363338780] 'agreement among raft nodes before linearized reading' (duration: 280.72767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:52.082301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.508846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:02:52.082512Z","caller":"traceutil/trace.go:171","msg":"trace[415305211] linearizableReadLoop","detail":"{readStateIndex:250; appliedIndex:248; }","duration":"580.981538ms","start":"2026-05-29T14:02:51.50151Z","end":"2026-05-29T14:02:52.082492Z","steps":["trace[415305211] 'read index received' (duration: 162.972496ms)","trace[415305211] 'applied index is now lower than readState.Index' (duration: 418.007922ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:02:52.082599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.421729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"warn","ts":"2026-05-29T14:02:52.082638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.670687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-29T14:02:52.082643Z","caller":"traceutil/trace.go:171","msg":"trace[1444058519] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:241; }","duration":"585.485381ms","start":"2026-05-29T14:02:51.497145Z","end":"2026-05-29T14:02:52.08263Z","steps":["trace[1444058519] 'agreement among raft nodes before linearized reading' (duration: 585.364947ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:02:52.082665Z","caller":"traceutil/trace.go:171","msg":"trace[2121440565] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:241; }","duration":"585.701768ms","start":"2026-05-29T14:02:51.496955Z","end":"2026-05-29T14:02:52.082657Z","steps":["trace[2121440565] 'agreement among raft nodes before linearized reading' (duration: 585.645686ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:02:52.082553Z","caller":"traceutil/trace.go:171","msg":"trace[1721088397] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"586.410301ms","start":"2026-05-29T14:02:51.496074Z","end":"2026-05-29T14:02:52.082484Z","steps":["trace[1721088397] 'process raft request' (duration: 168.462981ms)","trace[1721088397] 'compare' (duration: 417.32289ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:02:52.082702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:51.496231Z","time spent":"586.463742ms","remote":"127.0.0.1:51690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-29T14:02:52.082712Z","caller":"traceutil/trace.go:171","msg":"trace[336075539] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"585.710029ms","start":"2026-05-29T14:02:51.496995Z","end":"2026-05-29T14:02:52.082705Z","steps":["trace[336075539] 'process raft request' (duration: 585.421989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:52.082861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:51.496059Z","time spent":"586.664018ms","remote":"127.0.0.1:51414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T14:02:52.08281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.703258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:02:52.08289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.998966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:02:52.082962Z","caller":"traceutil/trace.go:171","msg":"trace[744400412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:241; }","duration":"238.110999ms","start":"2026-05-29T14:02:51.844831Z","end":"2026-05-29T14:02:52.082942Z","steps":["trace[744400412] 'agreement among raft nodes before linearized reading' (duration: 238.000416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:52.08297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:51.496987Z","time spent":"585.74874ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:02:52.082909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.018885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-29T14:02:52.082976Z","caller":"traceutil/trace.go:171","msg":"trace[1268807760] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:241; }","duration":"585.897004ms","start":"2026-05-29T14:02:51.497017Z","end":"2026-05-29T14:02:52.082914Z","steps":["trace[1268807760] 'agreement among raft nodes before linearized reading' (duration: 585.572394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:52.082678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:51.497135Z","time spent":"585.534702ms","remote":"127.0.0.1:51500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-29T14:02:52.082965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.186542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-05-29T14:02:52.083134Z","caller":"traceutil/trace.go:171","msg":"trace[2054947389] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:241; }","duration":"590.18489ms","start":"2026-05-29T14:02:51.492822Z","end":"2026-05-29T14:02:52.083007Z","steps":["trace[2054947389] 'agreement among raft nodes before linearized reading' (duration: 590.010995ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:02:52.083145Z","caller":"traceutil/trace.go:171","msg":"trace[1802358581] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:241; }","duration":"579.44785ms","start":"2026-05-29T14:02:51.503669Z","end":"2026-05-29T14:02:52.083117Z","steps":["trace[1802358581] 'agreement among raft nodes before linearized reading' (duration: 579.117359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:52.083186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.468501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:02:52.083173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:51.492778Z","time spent":"590.385486ms","remote":"127.0.0.1:51506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-05-29T14:02:52.083065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:51.496992Z","time spent":"586.027709ms","remote":"127.0.0.1:51442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"info","ts":"2026-05-29T14:02:52.083268Z","caller":"traceutil/trace.go:171","msg":"trace[955470433] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:241; }","duration":"579.558173ms","start":"2026-05-29T14:02:51.503693Z","end":"2026-05-29T14:02:52.083251Z","steps":["trace[955470433] 'agreement among raft nodes before linearized reading' (duration: 579.45765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:52.083224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:51.503662Z","time spent":"579.552713ms","remote":"127.0.0.1:51500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-29T14:02:52.083346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:51.503683Z","time spent":"579.652627ms","remote":"127.0.0.1:51522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-05-29T14:02:58.606668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857910263352679987,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T14:02:58.743934Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.477945131s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T14:02:58.744654Z","caller":"traceutil/trace.go:171","msg":"trace[1931961899] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"1.478723416s","start":"2026-05-29T14:02:57.265905Z","end":"2026-05-29T14:02:58.744629Z","steps":["trace[1931961899] 'process raft request' (duration: 1.478374175s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:58.744844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:57.265893Z","time spent":"1.47885926s","remote":"127.0.0.1:51506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:02:59.863215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019554071s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:02:59.863318Z","caller":"traceutil/trace.go:171","msg":"trace[1244584927] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:362; }","duration":"1.757539676s","start":"2026-05-29T14:02:58.105762Z","end":"2026-05-29T14:02:59.863302Z","steps":["trace[1244584927] 'read index received' (duration: 638.529882ms)","trace[1244584927] 'applied index is now lower than readState.Index' (duration: 1.119008463s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:02:59.863402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.757647969s","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-29T14:02:59.863464Z","caller":"traceutil/trace.go:171","msg":"trace[2128700202] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"1.533551515s","start":"2026-05-29T14:02:58.329877Z","end":"2026-05-29T14:02:59.863429Z","steps":["trace[2128700202] 'process raft request' (duration: 513.701954ms)","trace[2128700202] 'compare' (duration: 1.019418837s)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:02:59.863497Z","caller":"traceutil/trace.go:171","msg":"trace[1378813932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:351; }","duration":"1.757725552s","start":"2026-05-29T14:02:58.105746Z","end":"2026-05-29T14:02:59.863471Z","steps":["trace[1378813932] 'agreement among raft nodes before linearized reading' (duration: 1.757580197s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:59.863529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:58.329858Z","time spent":"1.533641948s","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:02:59.863547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:58.105687Z","time spent":"1.757836835s","remote":"127.0.0.1:51562","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-29T14:02:59.863666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.633419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-29T14:02:59.863671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114440069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-qhj8l\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-05-29T14:02:59.863689Z","caller":"traceutil/trace.go:171","msg":"trace[1224900645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:351; }","duration":"975.688751ms","start":"2026-05-29T14:02:58.887993Z","end":"2026-05-29T14:02:59.863682Z","steps":["trace[1224900645] 'agreement among raft nodes before linearized reading' (duration: 975.640679ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:02:59.8637Z","caller":"traceutil/trace.go:171","msg":"trace[257496427] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-qhj8l; range_end:; response_count:1; response_revision:351; }","duration":"1.114489591s","start":"2026-05-29T14:02:58.749201Z","end":"2026-05-29T14:02:59.86369Z","steps":["trace[257496427] 'agreement among raft nodes before linearized reading' (duration: 1.114437919s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:59.863733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.544403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.63\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-29T14:02:59.863729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:58.749185Z","time spent":"1.114538203s","remote":"127.0.0.1:51506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4485,"request content":"key:\"/registry/pods/kube-system/kube-proxy-qhj8l\" "} {"level":"info","ts":"2026-05-29T14:02:59.863749Z","caller":"traceutil/trace.go:171","msg":"trace[141180274] range","detail":"{range_begin:/registry/masterleases/199.19.213.63; range_end:; response_count:1; response_revision:351; }","duration":"309.586815ms","start":"2026-05-29T14:02:59.554157Z","end":"2026-05-29T14:02:59.863744Z","steps":["trace[141180274] 'agreement among raft nodes before linearized reading' (duration: 309.559874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:59.863765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:59.554138Z","time spent":"309.624096ms","remote":"127.0.0.1:51372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.63\" "} {"level":"warn","ts":"2026-05-29T14:02:59.863706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:58.887973Z","time spent":"975.729811ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T14:02:59.8638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.554640793s","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-29T14:02:59.863826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445564096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T14:02:59.86394Z","caller":"traceutil/trace.go:171","msg":"trace[446221289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:351; }","duration":"1.44570407s","start":"2026-05-29T14:02:58.418216Z","end":"2026-05-29T14:02:59.863921Z","steps":["trace[446221289] 'agreement among raft nodes before linearized reading' (duration: 1.445471653s)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:02:59.863875Z","caller":"traceutil/trace.go:171","msg":"trace[541907795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:351; }","duration":"1.554769307s","start":"2026-05-29T14:02:58.309087Z","end":"2026-05-29T14:02:59.863856Z","steps":["trace[541907795] 'agreement among raft nodes before linearized reading' (duration: 1.554490778s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:02:59.864056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:58.309068Z","time spent":"1.554962164s","remote":"127.0.0.1:51562","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-29T14:02:59.864412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:58.41815Z","time spent":"1.446242867s","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T14:03:00.958059Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.092764781s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-29T14:03:02.148058Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.188390404s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-29T14:03:02.156911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198628599s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-29T14:03:02.157091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:59.865205Z","time spent":"2.291879565s","remote":"127.0.0.1:51372","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-29T14:03:02.157188Z","caller":"traceutil/trace.go:171","msg":"trace[1296101920] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"2.289503449s","start":"2026-05-29T14:02:59.867668Z","end":"2026-05-29T14:03:02.157171Z","steps":["trace[1296101920] 'process raft request' (duration: 2.289436877s)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:02.15721Z","caller":"traceutil/trace.go:171","msg":"trace[9025129] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"2.289593083s","start":"2026-05-29T14:02:59.867589Z","end":"2026-05-29T14:03:02.157182Z","steps":["trace[9025129] 'process raft request' (duration: 2.289407397s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:02.157284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:59.867646Z","time spent":"2.289580402s","remote":"127.0.0.1:51562","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-29T14:03:02.157386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:59.867573Z","time spent":"2.289694015s","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:03:02.157494Z","caller":"traceutil/trace.go:171","msg":"trace[1299612225] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"2.288934461s","start":"2026-05-29T14:02:59.868536Z","end":"2026-05-29T14:03:02.15747Z","steps":["trace[1299612225] 'process raft request' (duration: 2.28860495s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:02.157547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:59.868522Z","time spent":"2.288997213s","remote":"127.0.0.1:51562","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-29T14:03:02.15762Z","caller":"traceutil/trace.go:171","msg":"trace[2105044598] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"2.285952577s","start":"2026-05-29T14:02:59.87165Z","end":"2026-05-29T14:03:02.157602Z","steps":["trace[2105044598] 'process raft request' (duration: 2.285708549s)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:02.157703Z","caller":"traceutil/trace.go:171","msg":"trace[1282456934] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:364; }","duration":"1.349624986s","start":"2026-05-29T14:03:00.808068Z","end":"2026-05-29T14:03:02.157693Z","steps":["trace[1282456934] 'read index received' (duration: 150.210592ms)","trace[1282456934] 'applied index is now lower than readState.Index' (duration: 1.199413604s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:03:02.157725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:59.871642Z","time spent":"2.286016569s","remote":"127.0.0.1:51506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:03:02.157745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349684637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:02.157771Z","caller":"traceutil/trace.go:171","msg":"trace[247577289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:356; }","duration":"1.349742489s","start":"2026-05-29T14:03:00.808021Z","end":"2026-05-29T14:03:02.157763Z","steps":["trace[247577289] 'agreement among raft nodes before linearized reading' (duration: 1.349701028s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:02.157789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:00.807998Z","time spent":"1.349786571s","remote":"127.0.0.1:51360","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-29T14:03:02.157854Z","caller":"traceutil/trace.go:171","msg":"trace[437234628] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"2.281232337s","start":"2026-05-29T14:02:59.876593Z","end":"2026-05-29T14:03:02.157825Z","steps":["trace[437234628] 'process raft request' (duration: 2.280964689s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:02.158037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:02:59.876567Z","time spent":"2.281348671s","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:03:28.787418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857910263352680214,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T14:03:28.907217Z","caller":"traceutil/trace.go:171","msg":"trace[1088538244] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"620.103318ms","start":"2026-05-29T14:03:28.287023Z","end":"2026-05-29T14:03:28.907126Z","steps":["trace[1088538244] 'read index received' (duration: 615.616677ms)","trace[1088538244] 'applied index is now lower than readState.Index' (duration: 4.484891ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:28.907417Z","caller":"traceutil/trace.go:171","msg":"trace[1372094510] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"727.555925ms","start":"2026-05-29T14:03:28.179835Z","end":"2026-05-29T14:03:28.907391Z","steps":["trace[1372094510] 'process raft request' (duration: 722.966551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:28.907628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:28.17981Z","time spent":"727.716531ms","remote":"127.0.0.1:51432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317296,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T14:03:28.908655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.04757ms","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-29T14:03:28.908748Z","caller":"traceutil/trace.go:171","msg":"trace[211905866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:448; }","duration":"616.175345ms","start":"2026-05-29T14:03:28.292551Z","end":"2026-05-29T14:03:28.908726Z","steps":["trace[211905866] 'agreement among raft nodes before linearized reading' (duration: 615.066899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:28.908794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:28.292539Z","time spent":"616.245386ms","remote":"127.0.0.1:51562","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-29T14:03:28.908996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.18135ms","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-29T14:03:28.909647Z","caller":"traceutil/trace.go:171","msg":"trace[516896852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:448; }","duration":"620.849182ms","start":"2026-05-29T14:03:28.288776Z","end":"2026-05-29T14:03:28.909625Z","steps":["trace[516896852] 'agreement among raft nodes before linearized reading' (duration: 618.809907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:28.909704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:28.288767Z","time spent":"620.917694ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T14:03:28.909184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.154603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T14:03:28.909802Z","caller":"traceutil/trace.go:171","msg":"trace[749656517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:448; }","duration":"622.802883ms","start":"2026-05-29T14:03:28.286982Z","end":"2026-05-29T14:03:28.909785Z","steps":["trace[749656517] 'agreement among raft nodes before linearized reading' (duration: 620.561752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:28.909261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.836031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T14:03:28.909902Z","caller":"traceutil/trace.go:171","msg":"trace[392380709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:448; }","duration":"614.498252ms","start":"2026-05-29T14:03:28.295373Z","end":"2026-05-29T14:03:28.909871Z","steps":["trace[392380709] 'agreement among raft nodes before linearized reading' (duration: 612.206339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:28.909956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:28.295352Z","time spent":"614.590674ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T14:03:28.909917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:28.28696Z","time spent":"622.939518ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T14:03:31.348143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857910263352680280,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T14:03:31.648205Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.083817536s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T14:03:31.64861Z","caller":"traceutil/trace.go:171","msg":"trace[161425271] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.084283732s","start":"2026-05-29T14:03:30.564301Z","end":"2026-05-29T14:03:31.648585Z","steps":["trace[161425271] 'process raft request' (duration: 1.084153577s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.648757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.564285Z","time spent":"1.084399595s","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T14:03:31.666662Z","caller":"traceutil/trace.go:171","msg":"trace[43301834] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.097929612s","start":"2026-05-29T14:03:30.568715Z","end":"2026-05-29T14:03:31.666645Z","steps":["trace[43301834] 'process raft request' (duration: 1.097794898s)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:31.666777Z","caller":"traceutil/trace.go:171","msg":"trace[571001446] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.084485848s","start":"2026-05-29T14:03:30.582218Z","end":"2026-05-29T14:03:31.666704Z","steps":["trace[571001446] 'process raft request' (duration: 1.084362644s)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:31.666798Z","caller":"traceutil/trace.go:171","msg":"trace[666313726] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.082095642s","start":"2026-05-29T14:03:30.584679Z","end":"2026-05-29T14:03:31.666775Z","steps":["trace[666313726] 'process raft request' (duration: 1.081945007s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.666842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.568707Z","time spent":"1.098065116s","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T14:03:31.666868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.584673Z","time spent":"1.082161435s","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T14:03:31.666871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.582199Z","time spent":"1.084626773s","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T14:03:31.666966Z","caller":"traceutil/trace.go:171","msg":"trace[314308213] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.078977154s","start":"2026-05-29T14:03:30.587978Z","end":"2026-05-29T14:03:31.666955Z","steps":["trace[314308213] 'process raft request' (duration: 1.078682554s)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:31.666708Z","caller":"traceutil/trace.go:171","msg":"trace[2034858361] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.090640772s","start":"2026-05-29T14:03:30.576028Z","end":"2026-05-29T14:03:31.666668Z","steps":["trace[2034858361] 'process raft request' (duration: 1.090514788s)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:31.66699Z","caller":"traceutil/trace.go:171","msg":"trace[77156430] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.075647149s","start":"2026-05-29T14:03:30.591328Z","end":"2026-05-29T14:03:31.666975Z","steps":["trace[77156430] 'process raft request' (duration: 1.075510065s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.667072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.587959Z","time spent":"1.079049576s","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T14:03:31.667082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.591313Z","time spent":"1.075735502s","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T14:03:31.667092Z","caller":"traceutil/trace.go:171","msg":"trace[1928756909] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.063980181s","start":"2026-05-29T14:03:30.603096Z","end":"2026-05-29T14:03:31.667076Z","steps":["trace[1928756909] 'process raft request' (duration: 1.063804976s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.667167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.60308Z","time spent":"1.064053983s","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T14:03:31.667202Z","caller":"traceutil/trace.go:171","msg":"trace[1677556733] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.055987289s","start":"2026-05-29T14:03:30.611201Z","end":"2026-05-29T14:03:31.667188Z","steps":["trace[1677556733] 'process raft request' (duration: 1.055751802s)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:31.6666Z","caller":"traceutil/trace.go:171","msg":"trace[2040172550] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.098137299s","start":"2026-05-29T14:03:30.568436Z","end":"2026-05-29T14:03:31.666574Z","steps":["trace[2040172550] 'process raft request' (duration: 1.097978754s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.667289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.611175Z","time spent":"1.056076812s","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T14:03:31.66734Z","caller":"traceutil/trace.go:171","msg":"trace[692616322] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.038921031s","start":"2026-05-29T14:03:30.628395Z","end":"2026-05-29T14:03:31.667316Z","steps":["trace[692616322] 'process raft request' (duration: 1.038687214s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.667393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.628373Z","time spent":"1.039001504s","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T14:03:31.66743Z","caller":"traceutil/trace.go:171","msg":"trace[780627953] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:511; }","duration":"820.166195ms","start":"2026-05-29T14:03:30.847237Z","end":"2026-05-29T14:03:31.667403Z","steps":["trace[780627953] 'read index received' (duration: 801.328701ms)","trace[780627953] 'applied index is now lower than readState.Index' (duration: 18.836324ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:31.667456Z","caller":"traceutil/trace.go:171","msg":"trace[1930134060] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"926.289111ms","start":"2026-05-29T14:03:30.74115Z","end":"2026-05-29T14:03:31.667439Z","steps":["trace[1930134060] 'process raft request' (duration: 926.101325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.667586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.32862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:31.667628Z","caller":"traceutil/trace.go:171","msg":"trace[1530531033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"820.440334ms","start":"2026-05-29T14:03:30.84718Z","end":"2026-05-29T14:03:31.66762Z","steps":["trace[1530531033] 'agreement among raft nodes before linearized reading' (duration: 820.297949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.667662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.84716Z","time spent":"820.495656ms","remote":"127.0.0.1:51356","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-29T14:03:31.667655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.576019Z","time spent":"1.091062925s","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T14:03:31.667537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.741129Z","time spent":"926.374624ms","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T14:03:31.668603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.568428Z","time spent":"1.098863891s","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T14:03:31.669577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.589822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T14:03:31.669637Z","caller":"traceutil/trace.go:171","msg":"trace[1481241308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"724.702786ms","start":"2026-05-29T14:03:30.944919Z","end":"2026-05-29T14:03:31.669622Z","steps":["trace[1481241308] 'agreement among raft nodes before linearized reading' (duration: 724.571522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.66968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.944901Z","time spent":"724.769608ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T14:03:31.670045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.578609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T14:03:31.670053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.135665ms","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-29T14:03:31.670089Z","caller":"traceutil/trace.go:171","msg":"trace[1697085039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:502; }","duration":"722.668002ms","start":"2026-05-29T14:03:30.94741Z","end":"2026-05-29T14:03:31.670078Z","steps":["trace[1697085039] 'agreement among raft nodes before linearized reading' (duration: 722.59269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.670127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.947386Z","time spent":"722.732764ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T14:03:31.670142Z","caller":"traceutil/trace.go:171","msg":"trace[150781784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:502; }","duration":"722.245759ms","start":"2026-05-29T14:03:30.947879Z","end":"2026-05-29T14:03:31.670125Z","steps":["trace[150781784] 'agreement among raft nodes before linearized reading' (duration: 722.094624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.670316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.947871Z","time spent":"722.366072ms","remote":"127.0.0.1:51562","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-29T14:03:31.670649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.692583ms","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-29T14:03:31.670956Z","caller":"traceutil/trace.go:171","msg":"trace[1041682568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:502; }","duration":"722.766476ms","start":"2026-05-29T14:03:30.947923Z","end":"2026-05-29T14:03:31.67069Z","steps":["trace[1041682568] 'agreement among raft nodes before linearized reading' (duration: 722.678613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.671179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:30.947913Z","time spent":"723.098826ms","remote":"127.0.0.1:51562","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-29T14:03:38.347648Z","caller":"traceutil/trace.go:171","msg":"trace[672983047] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"242.215436ms","start":"2026-05-29T14:03:38.10537Z","end":"2026-05-29T14:03:38.347585Z","steps":["trace[672983047] 'process raft request' (duration: 241.294237ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:38.494475Z","caller":"traceutil/trace.go:171","msg":"trace[957257080] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"123.838114ms","start":"2026-05-29T14:03:38.370609Z","end":"2026-05-29T14:03:38.494447Z","steps":["trace[957257080] 'process raft request' (duration: 123.630757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:39.348435Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857910263352680458,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T14:03:39.621187Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.126916865s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T14:03:39.621528Z","caller":"traceutil/trace.go:171","msg":"trace[1305019263] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"776.201899ms","start":"2026-05-29T14:03:38.845297Z","end":"2026-05-29T14:03:39.621499Z","steps":["trace[1305019263] 'read index received' (duration: 776.008853ms)","trace[1305019263] 'applied index is now lower than readState.Index' (duration: 191.966µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:39.62157Z","caller":"traceutil/trace.go:171","msg":"trace[238751791] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.222720085s","start":"2026-05-29T14:03:38.398818Z","end":"2026-05-29T14:03:39.621538Z","steps":["trace[238751791] 'process raft request' (duration: 1.222539159s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:39.621678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.372355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:39.621719Z","caller":"traceutil/trace.go:171","msg":"trace[673796491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"776.461307ms","start":"2026-05-29T14:03:38.845247Z","end":"2026-05-29T14:03:39.621708Z","steps":["trace[673796491] 'agreement among raft nodes before linearized reading' (duration: 776.365764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:39.621723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:38.3987Z","time spent":"1.222958273s","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T14:03:39.621756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:38.845227Z","time spent":"776.520579ms","remote":"127.0.0.1:51360","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-29T14:03:41.610718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.97091487s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-29T14:03:41.610948Z","caller":"traceutil/trace.go:171","msg":"trace[2009155929] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.079416457s","start":"2026-05-29T14:03:40.531519Z","end":"2026-05-29T14:03:41.610935Z","steps":["trace[2009155929] 'process raft request' (duration: 1.079304654s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:41.611062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:40.531481Z","time spent":"1.079532431s","remote":"127.0.0.1:51562","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-29T14:03:41.611116Z","caller":"traceutil/trace.go:171","msg":"trace[854631987] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"1.984352965s","start":"2026-05-29T14:03:39.626735Z","end":"2026-05-29T14:03:41.611088Z","steps":["trace[854631987] 'read index received' (duration: 12.99319ms)","trace[854631987] 'applied index is now lower than readState.Index' (duration: 1.971357975s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:03:41.611224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:39.622906Z","time spent":"1.988314119s","remote":"127.0.0.1:51372","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-29T14:03:41.611435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.984680465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-29T14:03:41.611469Z","caller":"traceutil/trace.go:171","msg":"trace[2041794164] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:603; }","duration":"1.984749847s","start":"2026-05-29T14:03:39.626708Z","end":"2026-05-29T14:03:41.611458Z","steps":["trace[2041794164] 'agreement among raft nodes before linearized reading' (duration: 1.984664574s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:41.611497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:39.626696Z","time spent":"1.984794859s","remote":"127.0.0.1:51522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-29T14:03:41.611786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.882328208s","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-29T14:03:41.611789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.881875364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T14:03:41.611814Z","caller":"traceutil/trace.go:171","msg":"trace[1562188465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"1.88237469s","start":"2026-05-29T14:03:39.72943Z","end":"2026-05-29T14:03:41.611805Z","steps":["trace[1562188465] 'agreement among raft nodes before linearized reading' (duration: 1.882275786s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:41.611839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.883145974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T14:03:41.61188Z","caller":"traceutil/trace.go:171","msg":"trace[1738934309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"1.883248487s","start":"2026-05-29T14:03:39.72862Z","end":"2026-05-29T14:03:41.611869Z","steps":["trace[1738934309] 'agreement among raft nodes before linearized reading' (duration: 1.883109102s)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:41.611827Z","caller":"traceutil/trace.go:171","msg":"trace[500583146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"1.881924456s","start":"2026-05-29T14:03:39.729889Z","end":"2026-05-29T14:03:41.611813Z","steps":["trace[500583146] 'agreement among raft nodes before linearized reading' (duration: 1.881814162s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:41.611905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:39.729881Z","time spent":"1.882018549s","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T14:03:41.611907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:39.728566Z","time spent":"1.88333436s","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T14:03:41.611789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.762539292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:03:41.611877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:39.729422Z","time spent":"1.882448762s","remote":"127.0.0.1:51562","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-29T14:03:41.611957Z","caller":"traceutil/trace.go:171","msg":"trace[610802624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"1.762759839s","start":"2026-05-29T14:03:39.849185Z","end":"2026-05-29T14:03:41.611945Z","steps":["trace[610802624] 'agreement among raft nodes before linearized reading' (duration: 1.762547202s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:41.611985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:39.849159Z","time spent":"1.762818571s","remote":"127.0.0.1:51360","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-29T14:03:41.612366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.883889827s","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-29T14:03:41.612404Z","caller":"traceutil/trace.go:171","msg":"trace[1098809216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"1.88397421s","start":"2026-05-29T14:03:39.72842Z","end":"2026-05-29T14:03:41.612395Z","steps":["trace[1098809216] 'agreement among raft nodes before linearized reading' (duration: 1.883173215s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:41.612444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:39.728401Z","time spent":"1.884036241s","remote":"127.0.0.1:51562","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-29T14:03:41.776551Z","caller":"traceutil/trace.go:171","msg":"trace[977016312] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"160.801699ms","start":"2026-05-29T14:03:41.61572Z","end":"2026-05-29T14:03:41.776522Z","steps":["trace[977016312] 'process raft request' (duration: 160.497099ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:41.776559Z","caller":"traceutil/trace.go:171","msg":"trace[175483952] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"160.793409ms","start":"2026-05-29T14:03:41.615737Z","end":"2026-05-29T14:03:41.776531Z","steps":["trace[175483952] 'process raft request' (duration: 160.685896ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:41.776643Z","caller":"traceutil/trace.go:171","msg":"trace[390077069] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:630; }","duration":"122.291555ms","start":"2026-05-29T14:03:41.654334Z","end":"2026-05-29T14:03:41.776625Z","steps":["trace[390077069] 'read index received' (duration: 121.796849ms)","trace[390077069] 'applied index is now lower than readState.Index' (duration: 493.816µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:41.776699Z","caller":"traceutil/trace.go:171","msg":"trace[443468123] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"160.425298ms","start":"2026-05-29T14:03:41.616247Z","end":"2026-05-29T14:03:41.776673Z","steps":["trace[443468123] 'process raft request' (duration: 160.234112ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:41.776738Z","caller":"traceutil/trace.go:171","msg":"trace[1342964564] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"159.855089ms","start":"2026-05-29T14:03:41.616874Z","end":"2026-05-29T14:03:41.776729Z","steps":["trace[1342964564] 'process raft request' (duration: 159.697674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:41.776833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.602682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-29T14:03:41.776878Z","caller":"traceutil/trace.go:171","msg":"trace[2013617293] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:608; }","duration":"120.686305ms","start":"2026-05-29T14:03:41.656177Z","end":"2026-05-29T14:03:41.776864Z","steps":["trace[2013617293] 'agreement among raft nodes before linearized reading' (duration: 120.575441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:41.776878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.116387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:03:41.776888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.045938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-29T14:03:41.776916Z","caller":"traceutil/trace.go:171","msg":"trace[631115812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"114.19464ms","start":"2026-05-29T14:03:41.662711Z","end":"2026-05-29T14:03:41.776905Z","steps":["trace[631115812] 'agreement among raft nodes before linearized reading' (duration: 114.124448ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:41.776934Z","caller":"traceutil/trace.go:171","msg":"trace[791521827] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:608; }","duration":"162.12209ms","start":"2026-05-29T14:03:41.614797Z","end":"2026-05-29T14:03:41.776919Z","steps":["trace[791521827] 'agreement among raft nodes before linearized reading' (duration: 162.033178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:56.977421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857910263352680842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T14:03:57.477792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857910263352680842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T14:03:57.738294Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.265505484s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T14:03:57.738653Z","caller":"traceutil/trace.go:171","msg":"trace[1393646538] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"1.261706835s","start":"2026-05-29T14:03:56.476921Z","end":"2026-05-29T14:03:57.738627Z","steps":["trace[1393646538] 'read index received' (duration: 1.261475368s)","trace[1393646538] 'applied index is now lower than readState.Index' (duration: 230.537µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:57.738736Z","caller":"traceutil/trace.go:171","msg":"trace[1656062191] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"1.265997299s","start":"2026-05-29T14:03:56.472709Z","end":"2026-05-29T14:03:57.738706Z","steps":["trace[1656062191] 'process raft request' (duration: 1.265785553s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.738846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261913071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:57.738888Z","caller":"traceutil/trace.go:171","msg":"trace[550183486] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:766; }","duration":"1.261987794s","start":"2026-05-29T14:03:56.476889Z","end":"2026-05-29T14:03:57.738877Z","steps":["trace[550183486] 'agreement among raft nodes before linearized reading' (duration: 1.261842269s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.73893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.476882Z","time spent":"1.262038175s","remote":"127.0.0.1:32966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-05-29T14:03:57.738959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.472696Z","time spent":"1.266133674s","remote":"127.0.0.1:51506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:03:57.739163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262102057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:57.7392Z","caller":"traceutil/trace.go:171","msg":"trace[1073910743] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:766; }","duration":"1.262151109s","start":"2026-05-29T14:03:56.47704Z","end":"2026-05-29T14:03:57.739191Z","steps":["trace[1073910743] 'agreement among raft nodes before linearized reading' (duration: 1.262098927s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.739235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.477035Z","time spent":"1.26219122s","remote":"127.0.0.1:32966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-29T14:03:57.739223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21015201s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:57.739309Z","caller":"traceutil/trace.go:171","msg":"trace[276681129] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:766; }","duration":"1.210267783s","start":"2026-05-29T14:03:56.529024Z","end":"2026-05-29T14:03:57.739292Z","steps":["trace[276681129] 'agreement among raft nodes before linearized reading' (duration: 1.210128398s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.739339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.701843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-29T14:03:57.739367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.529008Z","time spent":"1.210339896s","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"info","ts":"2026-05-29T14:03:57.739394Z","caller":"traceutil/trace.go:171","msg":"trace[990166987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"617.788686ms","start":"2026-05-29T14:03:57.121592Z","end":"2026-05-29T14:03:57.739381Z","steps":["trace[990166987] 'agreement among raft nodes before linearized reading' (duration: 617.568449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.739436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:57.121561Z","time spent":"617.864108ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T14:03:57.739661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.896965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:57.739715Z","caller":"traceutil/trace.go:171","msg":"trace[1236277579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"890.981528ms","start":"2026-05-29T14:03:56.84872Z","end":"2026-05-29T14:03:57.739701Z","steps":["trace[1236277579] 'agreement among raft nodes before linearized reading' (duration: 890.902725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.739762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.848698Z","time spent":"891.053449ms","remote":"127.0.0.1:51360","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-29T14:03:57.739975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210354686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:57.740029Z","caller":"traceutil/trace.go:171","msg":"trace[21201580] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:766; }","duration":"1.210426038s","start":"2026-05-29T14:03:56.52959Z","end":"2026-05-29T14:03:57.740016Z","steps":["trace[21201580] 'agreement among raft nodes before linearized reading' (duration: 1.210348475s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.740071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244802172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:03:57.740141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210964795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:57.740185Z","caller":"traceutil/trace.go:171","msg":"trace[1171542543] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:766; }","duration":"1.211064529s","start":"2026-05-29T14:03:56.529111Z","end":"2026-05-29T14:03:57.740175Z","steps":["trace[1171542543] 'agreement among raft nodes before linearized reading' (duration: 1.211001237s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.740226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.529098Z","time spent":"1.21111754s","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-29T14:03:57.740376Z","caller":"traceutil/trace.go:171","msg":"trace[1030280273] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:766; }","duration":"1.244892635s","start":"2026-05-29T14:03:56.495227Z","end":"2026-05-29T14:03:57.74012Z","steps":["trace[1030280273] 'agreement among raft nodes before linearized reading' (duration: 1.244798852s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.740469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.495213Z","time spent":"1.245234655s","remote":"127.0.0.1:32982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-05-29T14:03:57.740826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226296199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:57.740884Z","caller":"traceutil/trace.go:171","msg":"trace[1069522701] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:766; }","duration":"1.226369631s","start":"2026-05-29T14:03:56.514501Z","end":"2026-05-29T14:03:57.740871Z","steps":["trace[1069522701] 'agreement among raft nodes before linearized reading' (duration: 1.226284198s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.740896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24663232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:03:57.740896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225900986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:03:57.740928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.514495Z","time spent":"1.226416862s","remote":"127.0.0.1:32998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-29T14:03:57.740964Z","caller":"traceutil/trace.go:171","msg":"trace[1202096621] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:766; }","duration":"1.22601977s","start":"2026-05-29T14:03:56.514929Z","end":"2026-05-29T14:03:57.740949Z","steps":["trace[1202096621] 'agreement among raft nodes before linearized reading' (duration: 1.225866805s)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:57.740954Z","caller":"traceutil/trace.go:171","msg":"trace[59438160] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:766; }","duration":"1.246707932s","start":"2026-05-29T14:03:56.494231Z","end":"2026-05-29T14:03:57.740939Z","steps":["trace[59438160] 'agreement among raft nodes before linearized reading' (duration: 1.246610579s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.741002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.514918Z","time spent":"1.226077492s","remote":"127.0.0.1:32998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-29T14:03:57.741004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.494224Z","time spent":"1.246769934s","remote":"127.0.0.1:32982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-05-29T14:03:57.740079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.529581Z","time spent":"1.21048379s","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-05-29T14:03:57.74117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263363997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:57.741232Z","caller":"traceutil/trace.go:171","msg":"trace[20022088] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:766; }","duration":"1.263441359s","start":"2026-05-29T14:03:56.477768Z","end":"2026-05-29T14:03:57.74121Z","steps":["trace[20022088] 'agreement among raft nodes before linearized reading' (duration: 1.263307805s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.741268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.477763Z","time spent":"1.263496051s","remote":"127.0.0.1:32966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-29T14:03:57.741472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246776764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:57.741508Z","caller":"traceutil/trace.go:171","msg":"trace[145585981] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:766; }","duration":"1.247032212s","start":"2026-05-29T14:03:56.494466Z","end":"2026-05-29T14:03:57.741498Z","steps":["trace[145585981] 'agreement among raft nodes before linearized reading' (duration: 1.246796185s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.741537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.494445Z","time spent":"1.247084273s","remote":"127.0.0.1:32982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-29T14:03:57.742462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226134273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:57.742506Z","caller":"traceutil/trace.go:171","msg":"trace[2023208273] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:766; }","duration":"1.227872958s","start":"2026-05-29T14:03:56.51462Z","end":"2026-05-29T14:03:57.742493Z","steps":["trace[2023208273] 'agreement among raft nodes before linearized reading' (duration: 1.226137243s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:57.742539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:56.514596Z","time spent":"1.22793417s","remote":"127.0.0.1:32998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-29T14:04:32.904756Z","caller":"traceutil/trace.go:171","msg":"trace[859996708] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"263.879278ms","start":"2026-05-29T14:04:32.640853Z","end":"2026-05-29T14:04:32.904733Z","steps":["trace[859996708] 'process raft request' (duration: 263.713153ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:04:33.640743Z","caller":"traceutil/trace.go:171","msg":"trace[457561221] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"745.507926ms","start":"2026-05-29T14:04:32.895215Z","end":"2026-05-29T14:04:33.640723Z","steps":["trace[457561221] 'process raft request' (duration: 744.862966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:33.640866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:32.89519Z","time spent":"745.61081ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:04:34.01152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.524466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:04:34.011593Z","caller":"traceutil/trace.go:171","msg":"trace[31079183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"163.64337ms","start":"2026-05-29T14:04:33.847935Z","end":"2026-05-29T14:04:34.011578Z","steps":["trace[31079183] 'range keys from in-memory index tree' (duration: 163.447824ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:04:54.961678Z","caller":"traceutil/trace.go:171","msg":"trace[1313145516] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"115.436706ms","start":"2026-05-29T14:04:54.846212Z","end":"2026-05-29T14:04:54.961649Z","steps":["trace[1313145516] 'read index received' (duration: 115.193199ms)","trace[1313145516] 'applied index is now lower than readState.Index' (duration: 242.387µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:04:54.961779Z","caller":"traceutil/trace.go:171","msg":"trace[534101065] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"133.40111ms","start":"2026-05-29T14:04:54.828338Z","end":"2026-05-29T14:04:54.961739Z","steps":["trace[534101065] 'process raft request' (duration: 133.135192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:54.961857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.623502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:04:54.961906Z","caller":"traceutil/trace.go:171","msg":"trace[1704796625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"115.741205ms","start":"2026-05-29T14:04:54.846151Z","end":"2026-05-29T14:04:54.961893Z","steps":["trace[1704796625] 'agreement among raft nodes before linearized reading' (duration: 115.645442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:56.75615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.929285ms","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-29T14:04:56.756231Z","caller":"traceutil/trace.go:171","msg":"trace[1226751443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"594.042809ms","start":"2026-05-29T14:04:56.162168Z","end":"2026-05-29T14:04:56.756211Z","steps":["trace[1226751443] 'range keys from in-memory index tree' (duration: 593.78204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:56.756253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134268545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:04:56.756281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:56.162155Z","time spent":"594.118541ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T14:04:56.756318Z","caller":"traceutil/trace.go:171","msg":"trace[374488571] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1056; }","duration":"1.134363948s","start":"2026-05-29T14:04:55.621929Z","end":"2026-05-29T14:04:56.756293Z","steps":["trace[374488571] 'count revisions from in-memory index tree' (duration: 1.134178333s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:56.756544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:55.621909Z","time spent":"1.134454471s","remote":"127.0.0.1:51708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-29T14:04:56.756593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.334224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-29T14:04:56.756676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.143183ms","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-29T14:04:56.756688Z","caller":"traceutil/trace.go:171","msg":"trace[604609508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"648.506089ms","start":"2026-05-29T14:04:56.108163Z","end":"2026-05-29T14:04:56.756669Z","steps":["trace[604609508] 'range keys from in-memory index tree' (duration: 648.169099ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:04:56.756726Z","caller":"traceutil/trace.go:171","msg":"trace[651228524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1056; }","duration":"581.203435ms","start":"2026-05-29T14:04:56.175506Z","end":"2026-05-29T14:04:56.75671Z","steps":["trace[651228524] 'range keys from in-memory index tree' (duration: 581.02465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:56.756732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:56.108146Z","time spent":"648.577242ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T14:04:56.756768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:56.175496Z","time spent":"581.264677ms","remote":"127.0.0.1:51562","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-29T14:04:56.756313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.446409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:04:56.756866Z","caller":"traceutil/trace.go:171","msg":"trace[1961199762] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1056; }","duration":"729.019257ms","start":"2026-05-29T14:04:56.027836Z","end":"2026-05-29T14:04:56.756856Z","steps":["trace[1961199762] 'count revisions from in-memory index tree' (duration: 728.318624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:56.756899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:56.02778Z","time spent":"729.10778ms","remote":"127.0.0.1:51586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-29T14:04:56.756581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.773157ms","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-29T14:04:56.756959Z","caller":"traceutil/trace.go:171","msg":"trace[271586890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1056; }","duration":"592.193841ms","start":"2026-05-29T14:04:56.164756Z","end":"2026-05-29T14:04:56.756949Z","steps":["trace[271586890] 'range keys from in-memory index tree' (duration: 591.589191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:56.756997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:56.164735Z","time spent":"592.253812ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T14:04:56.757229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.559355ms","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-29T14:04:56.757277Z","caller":"traceutil/trace.go:171","msg":"trace[956033285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1056; }","duration":"573.646868ms","start":"2026-05-29T14:04:56.18362Z","end":"2026-05-29T14:04:56.757266Z","steps":["trace[956033285] 'range keys from in-memory index tree' (duration: 573.409721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:56.757305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:56.1836Z","time spent":"573.69781ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T14:04:56.758472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.703592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:04:56.758587Z","caller":"traceutil/trace.go:171","msg":"trace[1792038876] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1056; }","duration":"638.905388ms","start":"2026-05-29T14:04:56.119667Z","end":"2026-05-29T14:04:56.758573Z","steps":["trace[1792038876] 'count revisions from in-memory index tree' (duration: 638.618539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:56.758626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:56.119645Z","time spent":"638.96898ms","remote":"127.0.0.1:39516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-05-29T14:04:59.122024Z","caller":"traceutil/trace.go:171","msg":"trace[2068982592] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"303.502043ms","start":"2026-05-29T14:04:58.818499Z","end":"2026-05-29T14:04:59.122001Z","steps":["trace[2068982592] 'read index received' (duration: 303.340818ms)","trace[2068982592] 'applied index is now lower than readState.Index' (duration: 160.305µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:04:59.122103Z","caller":"traceutil/trace.go:171","msg":"trace[786693123] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"340.091902ms","start":"2026-05-29T14:04:58.781987Z","end":"2026-05-29T14:04:59.122078Z","steps":["trace[786693123] 'process raft request' (duration: 339.882836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:59.122214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:58.78196Z","time spent":"340.191816ms","remote":"127.0.0.1:51562","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-29T14:04:59.122229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.710209ms","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-29T14:04:59.122261Z","caller":"traceutil/trace.go:171","msg":"trace[1403562432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"303.787642ms","start":"2026-05-29T14:04:58.818464Z","end":"2026-05-29T14:04:59.122251Z","steps":["trace[1403562432] 'agreement among raft nodes before linearized reading' (duration: 303.647337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:59.122303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:58.818448Z","time spent":"303.846273ms","remote":"127.0.0.1:51562","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-29T14:04:59.228192Z","caller":"traceutil/trace.go:171","msg":"trace[386648955] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"106.079832ms","start":"2026-05-29T14:04:59.122087Z","end":"2026-05-29T14:04:59.228167Z","steps":["trace[386648955] 'read index received' (duration: 102.440608ms)","trace[386648955] 'applied index is now lower than readState.Index' (duration: 3.638524ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:04:59.22828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.102352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:04:59.228334Z","caller":"traceutil/trace.go:171","msg":"trace[249728754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1064; }","duration":"382.191254ms","start":"2026-05-29T14:04:58.846128Z","end":"2026-05-29T14:04:59.228319Z","steps":["trace[249728754] 'agreement among raft nodes before linearized reading' (duration: 382.06554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:59.228382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:58.846111Z","time spent":"382.260357ms","remote":"127.0.0.1:51360","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-29T14:04:59.228417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.681957ms","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-29T14:04:59.228502Z","caller":"traceutil/trace.go:171","msg":"trace[441148523] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"208.810038ms","start":"2026-05-29T14:04:59.019683Z","end":"2026-05-29T14:04:59.228493Z","steps":["trace[441148523] 'process raft request' (duration: 204.859784ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:04:59.228497Z","caller":"traceutil/trace.go:171","msg":"trace[404746293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1064; }","duration":"409.792111ms","start":"2026-05-29T14:04:58.818685Z","end":"2026-05-29T14:04:59.228478Z","steps":["trace[404746293] 'agreement among raft nodes before linearized reading' (duration: 409.563974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:59.228634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:58.818677Z","time spent":"409.939255ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T14:04:59.228448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.873664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-29T14:04:59.228734Z","caller":"traceutil/trace.go:171","msg":"trace[461749835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1064; }","duration":"410.195354ms","start":"2026-05-29T14:04:58.818531Z","end":"2026-05-29T14:04:59.228727Z","steps":["trace[461749835] 'agreement among raft nodes before linearized reading' (duration: 409.76886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:59.228777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:58.818512Z","time spent":"410.256535ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T14:04:59.228916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.225564ms","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-29T14:04:59.228957Z","caller":"traceutil/trace.go:171","msg":"trace[1079503843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1064; }","duration":"410.281837ms","start":"2026-05-29T14:04:58.818662Z","end":"2026-05-29T14:04:59.228944Z","steps":["trace[1079503843] 'agreement among raft nodes before linearized reading' (duration: 410.176193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:59.228996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:58.818656Z","time spent":"410.332658ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T14:04:59.239736Z","caller":"traceutil/trace.go:171","msg":"trace[1532550976] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"113.563387ms","start":"2026-05-29T14:04:59.126139Z","end":"2026-05-29T14:04:59.239702Z","steps":["trace[1532550976] 'process raft request' (duration: 113.400161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:01.743119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857910263352681538,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T14:05:01.834555Z","caller":"traceutil/trace.go:171","msg":"trace[1056697821] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"703.512288ms","start":"2026-05-29T14:05:01.131023Z","end":"2026-05-29T14:05:01.834535Z","steps":["trace[1056697821] 'process raft request' (duration: 703.384094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:01.834684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:01.131Z","time spent":"703.620822ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:05:02.012009Z","caller":"traceutil/trace.go:171","msg":"trace[1425253844] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1114; }","duration":"768.926012ms","start":"2026-05-29T14:05:01.243059Z","end":"2026-05-29T14:05:02.011985Z","steps":["trace[1425253844] 'read index received' (duration: 591.391076ms)","trace[1425253844] 'applied index is now lower than readState.Index' (duration: 177.531876ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:02.012137Z","caller":"traceutil/trace.go:171","msg":"trace[242505735] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"777.973166ms","start":"2026-05-29T14:05:01.234149Z","end":"2026-05-29T14:05:02.012122Z","steps":["trace[242505735] 'process raft request' (duration: 755.590623ms)","trace[242505735] 'compare' (duration: 22.109365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:02.012237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:01.234122Z","time spent":"778.042047ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:05:02.012415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.99505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-29T14:05:02.012446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.634081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:05:02.012549Z","caller":"traceutil/trace.go:171","msg":"trace[443732660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"164.773045ms","start":"2026-05-29T14:05:01.847757Z","end":"2026-05-29T14:05:02.01253Z","steps":["trace[443732660] 'agreement among raft nodes before linearized reading' (duration: 164.580569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:02.012591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.878047ms","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-29T14:05:02.012621Z","caller":"traceutil/trace.go:171","msg":"trace[1676007782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"758.930888ms","start":"2026-05-29T14:05:01.253681Z","end":"2026-05-29T14:05:02.012612Z","steps":["trace[1676007782] 'agreement among raft nodes before linearized reading' (duration: 758.845035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:02.012664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:01.253669Z","time spent":"758.98713ms","remote":"127.0.0.1:51562","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-29T14:05:02.012497Z","caller":"traceutil/trace.go:171","msg":"trace[2016454225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"759.116073ms","start":"2026-05-29T14:05:01.253363Z","end":"2026-05-29T14:05:02.012479Z","steps":["trace[2016454225] 'agreement among raft nodes before linearized reading' (duration: 758.870246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:02.012689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.645285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T14:05:02.012745Z","caller":"traceutil/trace.go:171","msg":"trace[1838942454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1071; }","duration":"769.741677ms","start":"2026-05-29T14:05:01.242992Z","end":"2026-05-29T14:05:02.012733Z","steps":["trace[1838942454] 'agreement among raft nodes before linearized reading' (duration: 769.589233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:02.012792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:01.242959Z","time spent":"769.8246ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T14:05:02.012609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.384861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T14:05:02.012897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:01.253345Z","time spent":"759.526607ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T14:05:02.013471Z","caller":"traceutil/trace.go:171","msg":"trace[2059573260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1071; }","duration":"758.912867ms","start":"2026-05-29T14:05:01.254141Z","end":"2026-05-29T14:05:02.013054Z","steps":["trace[2059573260] 'agreement among raft nodes before linearized reading' (duration: 758.264397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:02.013662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:01.254135Z","time spent":"759.394652ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T14:05:15.306837Z","caller":"traceutil/trace.go:171","msg":"trace[1331417882] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"118.622445ms","start":"2026-05-29T14:05:15.188185Z","end":"2026-05-29T14:05:15.306808Z","steps":["trace[1331417882] 'process raft request' (duration: 118.355527ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:18.063182Z","caller":"traceutil/trace.go:171","msg":"trace[1231706240] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"160.211232ms","start":"2026-05-29T14:05:17.902947Z","end":"2026-05-29T14:05:18.063158Z","steps":["trace[1231706240] 'process raft request' (duration: 160.060927ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:18.301016Z","caller":"traceutil/trace.go:171","msg":"trace[945265892] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"195.998766ms","start":"2026-05-29T14:05:18.104995Z","end":"2026-05-29T14:05:18.300994Z","steps":["trace[945265892] 'read index received' (duration: 193.145396ms)","trace[945265892] 'applied index is now lower than readState.Index' (duration: 2.85231ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:18.301102Z","caller":"traceutil/trace.go:171","msg":"trace[1992164837] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"240.508714ms","start":"2026-05-29T14:05:18.060563Z","end":"2026-05-29T14:05:18.301072Z","steps":["trace[1992164837] 'process raft request' (duration: 237.632314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:18.301319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.213732ms","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-29T14:05:18.301367Z","caller":"traceutil/trace.go:171","msg":"trace[840909131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1175; }","duration":"167.345956ms","start":"2026-05-29T14:05:18.134008Z","end":"2026-05-29T14:05:18.301354Z","steps":["trace[840909131] 'agreement among raft nodes before linearized reading' (duration: 167.16094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:18.301367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.724815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-29T14:05:18.301448Z","caller":"traceutil/trace.go:171","msg":"trace[1924197833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1175; }","duration":"158.831779ms","start":"2026-05-29T14:05:18.142599Z","end":"2026-05-29T14:05:18.30143Z","steps":["trace[1924197833] 'agreement among raft nodes before linearized reading' (duration: 158.590941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:18.301588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.134134ms","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-29T14:05:18.301627Z","caller":"traceutil/trace.go:171","msg":"trace[641190326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1175; }","duration":"163.223176ms","start":"2026-05-29T14:05:18.138392Z","end":"2026-05-29T14:05:18.301616Z","steps":["trace[641190326] 'agreement among raft nodes before linearized reading' (duration: 163.072322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:18.302741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.748051ms","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-29T14:05:18.303759Z","caller":"traceutil/trace.go:171","msg":"trace[768314906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1175; }","duration":"197.916476ms","start":"2026-05-29T14:05:18.104934Z","end":"2026-05-29T14:05:18.302851Z","steps":["trace[768314906] 'agreement among raft nodes before linearized reading' (duration: 196.203222ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:18.8797Z","caller":"traceutil/trace.go:171","msg":"trace[709336981] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"570.305343ms","start":"2026-05-29T14:05:18.30937Z","end":"2026-05-29T14:05:18.879675Z","steps":["trace[709336981] 'process raft request' (duration: 489.811255ms)","trace[709336981] 'compare' (duration: 80.360674ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:18.879824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:18.30935Z","time spent":"570.408587ms","remote":"127.0.0.1:51562","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-29T14:05:18.880892Z","caller":"traceutil/trace.go:171","msg":"trace[1720895148] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"569.941642ms","start":"2026-05-29T14:05:18.310937Z","end":"2026-05-29T14:05:18.880878Z","steps":["trace[1720895148] 'process raft request' (duration: 569.917971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:18.880959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:18.31093Z","time spent":"569.997034ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:05:18.881053Z","caller":"traceutil/trace.go:171","msg":"trace[1231162135] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"570.85272ms","start":"2026-05-29T14:05:18.310185Z","end":"2026-05-29T14:05:18.881038Z","steps":["trace[1231162135] 'process raft request' (duration: 570.644894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:18.881114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:18.310162Z","time spent":"570.925813ms","remote":"127.0.0.1:51562","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-29T14:05:18.88094Z","caller":"traceutil/trace.go:171","msg":"trace[1836889890] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"570.986045ms","start":"2026-05-29T14:05:18.309922Z","end":"2026-05-29T14:05:18.880908Z","steps":["trace[1836889890] 'process raft request' (duration: 570.84231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:18.88168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:18.309913Z","time spent":"571.264863ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:05:31.342358Z","caller":"traceutil/trace.go:171","msg":"trace[1885004552] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"391.02681ms","start":"2026-05-29T14:05:30.951302Z","end":"2026-05-29T14:05:31.342328Z","steps":["trace[1885004552] 'process raft request' (duration: 389.559984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:31.342525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:30.951288Z","time spent":"391.152885ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:05:31.343271Z","caller":"traceutil/trace.go:171","msg":"trace[1313114187] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1268; }","duration":"359.730846ms","start":"2026-05-29T14:05:30.983522Z","end":"2026-05-29T14:05:31.343253Z","steps":["trace[1313114187] 'read index received' (duration: 48.149003ms)","trace[1313114187] 'applied index is now lower than readState.Index' (duration: 311.580673ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:31.343345Z","caller":"traceutil/trace.go:171","msg":"trace[912738845] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"391.269468ms","start":"2026-05-29T14:05:30.95205Z","end":"2026-05-29T14:05:31.34332Z","steps":["trace[912738845] 'process raft request' (duration: 389.858284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:31.34366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:30.95203Z","time spent":"391.587028ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:05:31.343506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.973584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T14:05:31.343839Z","caller":"traceutil/trace.go:171","msg":"trace[1131804400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1218; }","duration":"360.329985ms","start":"2026-05-29T14:05:30.983498Z","end":"2026-05-29T14:05:31.343827Z","steps":["trace[1131804400] 'agreement among raft nodes before linearized reading' (duration: 359.893312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:31.343877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:30.983486Z","time spent":"360.381077ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T14:05:33.982236Z","caller":"traceutil/trace.go:171","msg":"trace[2117179896] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"134.778146ms","start":"2026-05-29T14:05:33.847437Z","end":"2026-05-29T14:05:33.982215Z","steps":["trace[2117179896] 'read index received' (duration: 134.632302ms)","trace[2117179896] 'applied index is now lower than readState.Index' (duration: 144.844µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:33.982313Z","caller":"traceutil/trace.go:171","msg":"trace[361113217] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"166.547024ms","start":"2026-05-29T14:05:33.815737Z","end":"2026-05-29T14:05:33.982284Z","steps":["trace[361113217] 'process raft request' (duration: 166.352558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:33.982346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.89049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:05:33.98238Z","caller":"traceutil/trace.go:171","msg":"trace[564641177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1242; }","duration":"134.970882ms","start":"2026-05-29T14:05:33.8474Z","end":"2026-05-29T14:05:33.982371Z","steps":["trace[564641177] 'agreement among raft nodes before linearized reading' (duration: 134.90166ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:34.167698Z","caller":"traceutil/trace.go:171","msg":"trace[1835755591] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"179.204062ms","start":"2026-05-29T14:05:33.988476Z","end":"2026-05-29T14:05:34.16768Z","steps":["trace[1835755591] 'process raft request' (duration: 179.043067ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:34.167883Z","caller":"traceutil/trace.go:171","msg":"trace[930241159] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"173.809743ms","start":"2026-05-29T14:05:33.994045Z","end":"2026-05-29T14:05:34.167855Z","steps":["trace[930241159] 'process raft request' (duration: 173.595536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:35.14667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.529763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:05:35.146869Z","caller":"traceutil/trace.go:171","msg":"trace[2027197667] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"752.215492ms","start":"2026-05-29T14:05:34.39463Z","end":"2026-05-29T14:05:35.146845Z","steps":["trace[2027197667] 'process raft request' (duration: 600.421991ms)","trace[2027197667] 'compare' (duration: 149.944323ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:35.146954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:34.394592Z","time spent":"752.325576ms","remote":"127.0.0.1:51562","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-29T14:05:35.14738Z","caller":"traceutil/trace.go:171","msg":"trace[2015235218] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"596.634282ms","start":"2026-05-29T14:05:34.550717Z","end":"2026-05-29T14:05:35.147351Z","steps":["trace[2015235218] 'process raft request' (duration: 596.075505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:35.147545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:34.55068Z","time spent":"596.776216ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:05:36.00402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.922923ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T14:05:36.004116Z","caller":"traceutil/trace.go:171","msg":"trace[897158294] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1299; }","duration":"960.455997ms","start":"2026-05-29T14:05:35.043642Z","end":"2026-05-29T14:05:36.004098Z","steps":["trace[897158294] 'read index received' (duration: 101.634664ms)","trace[897158294] 'applied index is now lower than readState.Index' (duration: 858.819733ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:36.00434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.687585ms","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-29T14:05:36.004378Z","caller":"traceutil/trace.go:171","msg":"trace[569520185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1248; }","duration":"960.771257ms","start":"2026-05-29T14:05:35.043595Z","end":"2026-05-29T14:05:36.004366Z","steps":["trace[569520185] 'agreement among raft nodes before linearized reading' (duration: 960.590211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:36.004412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:35.04357Z","time spent":"960.834029ms","remote":"127.0.0.1:51562","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-29T14:05:36.504798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857910263352681929,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T14:05:36.594021Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.22878189s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T14:05:36.594354Z","caller":"traceutil/trace.go:171","msg":"trace[847995512] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"1.229175343s","start":"2026-05-29T14:05:35.365156Z","end":"2026-05-29T14:05:36.594331Z","steps":["trace[847995512] 'process raft request' (duration: 1.229018828s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:36.594476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:35.365142Z","time spent":"1.229268145s","remote":"127.0.0.1:51562","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-29T14:05:36.791663Z","caller":"traceutil/trace.go:171","msg":"trace[1822335636] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1302; }","duration":"787.497971ms","start":"2026-05-29T14:05:36.004142Z","end":"2026-05-29T14:05:36.79164Z","steps":["trace[1822335636] 'read index received' (duration: 590.052796ms)","trace[1822335636] 'applied index is now lower than readState.Index' (duration: 197.444265ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:36.791766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.988153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:05:36.791835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430589213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T14:05:36.791872Z","caller":"traceutil/trace.go:171","msg":"trace[221429661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"1.430656685s","start":"2026-05-29T14:05:35.361203Z","end":"2026-05-29T14:05:36.79186Z","steps":["trace[221429661] 'agreement among raft nodes before linearized reading' (duration: 1.430538682s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:36.791876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.837535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:05:36.791886Z","caller":"traceutil/trace.go:171","msg":"trace[663539512] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"783.432854ms","start":"2026-05-29T14:05:36.008436Z","end":"2026-05-29T14:05:36.791869Z","steps":["trace[663539512] 'process raft request' (duration: 752.612325ms)","trace[663539512] 'compare' (duration: 30.299002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:36.791902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:35.361185Z","time spent":"1.430709027s","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T14:05:36.791914Z","caller":"traceutil/trace.go:171","msg":"trace[1520317845] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1250; }","duration":"580.912018ms","start":"2026-05-29T14:05:36.210989Z","end":"2026-05-29T14:05:36.791901Z","steps":["trace[1520317845] 'agreement among raft nodes before linearized reading' (duration: 580.848746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:36.791915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.698262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-29T14:05:36.791942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:36.210972Z","time spent":"580.9614ms","remote":"127.0.0.1:32944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-05-29T14:05:36.79197Z","caller":"traceutil/trace.go:171","msg":"trace[1385166534] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"601.790074ms","start":"2026-05-29T14:05:36.190163Z","end":"2026-05-29T14:05:36.791953Z","steps":["trace[1385166534] 'agreement among raft nodes before linearized reading' (duration: 601.635229ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:36.791832Z","caller":"traceutil/trace.go:171","msg":"trace[1842469421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"946.091096ms","start":"2026-05-29T14:05:35.845721Z","end":"2026-05-29T14:05:36.791812Z","steps":["trace[1842469421] 'agreement among raft nodes before linearized reading' (duration: 945.985123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:36.791983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.432771972s","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-29T14:05:36.791992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:36.008411Z","time spent":"783.513486ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:05:36.792013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:36.190145Z","time spent":"601.856557ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T14:05:36.79202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:35.845705Z","time spent":"946.308323ms","remote":"127.0.0.1:51360","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-29T14:05:36.792042Z","caller":"traceutil/trace.go:171","msg":"trace[1952409945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"1.432852505s","start":"2026-05-29T14:05:35.359174Z","end":"2026-05-29T14:05:36.792027Z","steps":["trace[1952409945] 'agreement among raft nodes before linearized reading' (duration: 1.43269797s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:36.792073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:35.359167Z","time spent":"1.432899896s","remote":"127.0.0.1:51562","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-29T14:05:36.79228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.435841978s","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-29T14:05:36.792341Z","caller":"traceutil/trace.go:171","msg":"trace[419522910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1250; }","duration":"1.435937121s","start":"2026-05-29T14:05:35.356389Z","end":"2026-05-29T14:05:36.792326Z","steps":["trace[419522910] 'agreement among raft nodes before linearized reading' (duration: 1.435755405s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:36.792385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:35.356371Z","time spent":"1.436001803s","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T14:05:39.127699Z","caller":"traceutil/trace.go:171","msg":"trace[188767357] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"289.956633ms","start":"2026-05-29T14:05:38.83772Z","end":"2026-05-29T14:05:39.127676Z","steps":["trace[188767357] 'read index received' (duration: 289.715605ms)","trace[188767357] 'applied index is now lower than readState.Index' (duration: 239.738µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:39.127793Z","caller":"traceutil/trace.go:171","msg":"trace[1753252501] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"326.574504ms","start":"2026-05-29T14:05:38.801192Z","end":"2026-05-29T14:05:39.127767Z","steps":["trace[1753252501] 'process raft request' (duration: 326.327116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:39.127869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.512606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:05:39.127928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:38.801167Z","time spent":"326.696348ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:05:39.127977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.234362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-29T14:05:39.128002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.016968ms","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-29T14:05:39.128039Z","caller":"traceutil/trace.go:171","msg":"trace[491454604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"264.074239ms","start":"2026-05-29T14:05:38.863955Z","end":"2026-05-29T14:05:39.12803Z","steps":["trace[491454604] 'agreement among raft nodes before linearized reading' (duration: 263.909714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:39.12804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.604686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T14:05:39.12812Z","caller":"traceutil/trace.go:171","msg":"trace[248872147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1257; }","duration":"264.684439ms","start":"2026-05-29T14:05:38.863422Z","end":"2026-05-29T14:05:39.128107Z","steps":["trace[248872147] 'agreement among raft nodes before linearized reading' (duration: 264.523663ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:39.128045Z","caller":"traceutil/trace.go:171","msg":"trace[2123396012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"290.356646ms","start":"2026-05-29T14:05:38.837655Z","end":"2026-05-29T14:05:39.128012Z","steps":["trace[2123396012] 'agreement among raft nodes before linearized reading' (duration: 290.16669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:39.128184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.434842ms","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-29T14:05:39.128267Z","caller":"traceutil/trace.go:171","msg":"trace[1857244191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"265.549006ms","start":"2026-05-29T14:05:38.862696Z","end":"2026-05-29T14:05:39.128245Z","steps":["trace[1857244191] 'agreement among raft nodes before linearized reading' (duration: 265.249056ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:39.127908Z","caller":"traceutil/trace.go:171","msg":"trace[1388304829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"280.585038ms","start":"2026-05-29T14:05:38.847313Z","end":"2026-05-29T14:05:39.127898Z","steps":["trace[1388304829] 'agreement among raft nodes before linearized reading' (duration: 280.504125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:39.321673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.337331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:05:39.321752Z","caller":"traceutil/trace.go:171","msg":"trace[1786906393] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"190.152536ms","start":"2026-05-29T14:05:39.131584Z","end":"2026-05-29T14:05:39.321736Z","steps":["trace[1786906393] 'process raft request' (duration: 56.677201ms)","trace[1786906393] 'compare' (duration: 133.131685ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:39.49843Z","caller":"traceutil/trace.go:171","msg":"trace[1864007659] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"366.099377ms","start":"2026-05-29T14:05:39.132304Z","end":"2026-05-29T14:05:39.498403Z","steps":["trace[1864007659] 'process raft request' (duration: 343.142846ms)","trace[1864007659] 'compare' (duration: 22.800016ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:39.498581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:39.132287Z","time spent":"366.221021ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:05:39.498727Z","caller":"traceutil/trace.go:171","msg":"trace[695893911] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"366.216411ms","start":"2026-05-29T14:05:39.132491Z","end":"2026-05-29T14:05:39.498707Z","steps":["trace[695893911] 'process raft request' (duration: 366.134178ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:39.498802Z","caller":"traceutil/trace.go:171","msg":"trace[1853785763] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"366.320734ms","start":"2026-05-29T14:05:39.132463Z","end":"2026-05-29T14:05:39.498784Z","steps":["trace[1853785763] 'process raft request' (duration: 365.900481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:39.498866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:39.132484Z","time spent":"366.318344ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:05:39.498868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:39.132434Z","time spent":"366.395647ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:05:39.54496Z","caller":"traceutil/trace.go:171","msg":"trace[1874190439] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"328.111963ms","start":"2026-05-29T14:05:39.216827Z","end":"2026-05-29T14:05:39.544939Z","steps":["trace[1874190439] 'process raft request' (duration: 327.981409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:39.545086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:39.216802Z","time spent":"328.217026ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:05:39.704618Z","caller":"traceutil/trace.go:171","msg":"trace[1041466920] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"110.490273ms","start":"2026-05-29T14:05:39.59411Z","end":"2026-05-29T14:05:39.7046Z","steps":["trace[1041466920] 'process raft request' (duration: 109.997458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:40.165529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.057032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:05:40.165719Z","caller":"traceutil/trace.go:171","msg":"trace[5340969] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"243.519664ms","start":"2026-05-29T14:05:39.922181Z","end":"2026-05-29T14:05:40.1657Z","steps":["trace[5340969] 'read index received' (duration: 61.662µs)","trace[5340969] 'applied index is now lower than readState.Index' (duration: 243.456152ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:40.165787Z","caller":"traceutil/trace.go:171","msg":"trace[867508943] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"375.477131ms","start":"2026-05-29T14:05:39.790259Z","end":"2026-05-29T14:05:40.165736Z","steps":["trace[867508943] 'process raft request' (duration: 18.14693ms)","trace[867508943] 'compare' (duration: 356.907458ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:40.16591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.683209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:05:40.165923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:39.790242Z","time spent":"375.621736ms","remote":"127.0.0.1:51414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T14:05:40.165993Z","caller":"traceutil/trace.go:171","msg":"trace[488560422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"243.834844ms","start":"2026-05-29T14:05:39.922117Z","end":"2026-05-29T14:05:40.165952Z","steps":["trace[488560422] 'agreement among raft nodes before linearized reading' (duration: 243.657798ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:41.364448Z","caller":"traceutil/trace.go:171","msg":"trace[523926901] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"229.848134ms","start":"2026-05-29T14:05:41.134578Z","end":"2026-05-29T14:05:41.364426Z","steps":["trace[523926901] 'process raft request' (duration: 229.542114ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:41.556395Z","caller":"traceutil/trace.go:171","msg":"trace[312308063] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"188.529436ms","start":"2026-05-29T14:05:41.367844Z","end":"2026-05-29T14:05:41.556373Z","steps":["trace[312308063] 'process raft request' (duration: 188.257997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.125891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8857910263352682060,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T14:05:48.565852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.229062ms","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-29T14:05:48.565979Z","caller":"traceutil/trace.go:171","msg":"trace[784985792] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1323; }","duration":"939.360696ms","start":"2026-05-29T14:05:47.626597Z","end":"2026-05-29T14:05:48.565958Z","steps":["trace[784985792] 'range keys from in-memory index tree' (duration: 939.21431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.566199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184225622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T14:05:48.566278Z","caller":"traceutil/trace.go:171","msg":"trace[1896516337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1323; }","duration":"1.184343035s","start":"2026-05-29T14:05:47.381922Z","end":"2026-05-29T14:05:48.566265Z","steps":["trace[1896516337] 'range keys from in-memory index tree' (duration: 1.184062996s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.566313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:47.381901Z","time spent":"1.184403708s","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T14:05:48.566648Z","caller":"traceutil/trace.go:171","msg":"trace[614877086] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1379; }","duration":"941.011697ms","start":"2026-05-29T14:05:47.625616Z","end":"2026-05-29T14:05:48.566628Z","steps":["trace[614877086] 'read index received' (duration: 939.53625ms)","trace[614877086] 'applied index is now lower than readState.Index' (duration: 1.474417ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:48.566766Z","caller":"traceutil/trace.go:171","msg":"trace[1978181002] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"947.46578ms","start":"2026-05-29T14:05:47.619275Z","end":"2026-05-29T14:05:48.566741Z","steps":["trace[1978181002] 'process raft request' (duration: 945.841819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.566846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:47.619253Z","time spent":"947.531932ms","remote":"127.0.0.1:51562","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-29T14:05:48.566987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.552502ms","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-29T14:05:48.567037Z","caller":"traceutil/trace.go:171","msg":"trace[540970851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1324; }","duration":"939.603653ms","start":"2026-05-29T14:05:47.627418Z","end":"2026-05-29T14:05:48.567022Z","steps":["trace[540970851] 'agreement among raft nodes before linearized reading' (duration: 939.473519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.567084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:47.627384Z","time spent":"939.688016ms","remote":"127.0.0.1:51562","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-29T14:05:48.56726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.649887ms","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-29T14:05:48.567309Z","caller":"traceutil/trace.go:171","msg":"trace[1341835083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1324; }","duration":"941.769461ms","start":"2026-05-29T14:05:47.625527Z","end":"2026-05-29T14:05:48.567297Z","steps":["trace[1341835083] 'agreement among raft nodes before linearized reading' (duration: 941.653807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.567347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:47.625515Z","time spent":"941.821432ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T14:05:48.567463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.589243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T14:05:48.567501Z","caller":"traceutil/trace.go:171","msg":"trace[2116312263] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1324; }","duration":"366.661365ms","start":"2026-05-29T14:05:48.200828Z","end":"2026-05-29T14:05:48.567489Z","steps":["trace[2116312263] 'agreement among raft nodes before linearized reading' (duration: 366.596913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.567784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:48.200811Z","time spent":"366.952473ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-29T14:05:48.567825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.666176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:05:48.567857Z","caller":"traceutil/trace.go:171","msg":"trace[1670480327] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1324; }","duration":"877.729818ms","start":"2026-05-29T14:05:47.690119Z","end":"2026-05-29T14:05:48.567849Z","steps":["trace[1670480327] 'agreement among raft nodes before linearized reading' (duration: 877.658296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.567878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:47.690102Z","time spent":"877.769689ms","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-29T14:05:48.568165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.994821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-29T14:05:48.568245Z","caller":"traceutil/trace.go:171","msg":"trace[680968987] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1324; }","duration":"783.092864ms","start":"2026-05-29T14:05:47.785121Z","end":"2026-05-29T14:05:48.568214Z","steps":["trace[680968987] 'agreement among raft nodes before linearized reading' (duration: 782.775364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.568298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:47.785103Z","time spent":"783.183297ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-29T14:05:48.568415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.737964ms","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-29T14:05:48.568223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.319363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T14:05:48.568503Z","caller":"traceutil/trace.go:171","msg":"trace[847159524] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1324; }","duration":"743.655974ms","start":"2026-05-29T14:05:47.824819Z","end":"2026-05-29T14:05:48.568475Z","steps":["trace[847159524] 'agreement among raft nodes before linearized reading' (duration: 742.844158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.568539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.219151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-05-29T14:05:48.568566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:47.824801Z","time spent":"743.747107ms","remote":"127.0.0.1:51522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-05-29T14:05:48.568589Z","caller":"traceutil/trace.go:171","msg":"trace[1155052950] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1324; }","duration":"590.303844ms","start":"2026-05-29T14:05:47.978268Z","end":"2026-05-29T14:05:48.568572Z","steps":["trace[1155052950] 'agreement among raft nodes before linearized reading' (duration: 590.151729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.568632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:47.978246Z","time spent":"590.377376ms","remote":"127.0.0.1:51398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-05-29T14:05:48.568475Z","caller":"traceutil/trace.go:171","msg":"trace[97371342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"937.817966ms","start":"2026-05-29T14:05:47.630644Z","end":"2026-05-29T14:05:48.568462Z","steps":["trace[97371342] 'agreement among raft nodes before linearized reading' (duration: 937.481886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.568827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:47.630635Z","time spent":"938.171787ms","remote":"127.0.0.1:51562","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-29T14:05:48.568866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.994867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T14:05:48.56975Z","caller":"traceutil/trace.go:171","msg":"trace[368169553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1324; }","duration":"941.901795ms","start":"2026-05-29T14:05:47.627826Z","end":"2026-05-29T14:05:48.569728Z","steps":["trace[368169553] 'agreement among raft nodes before linearized reading' (duration: 940.309295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.569834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:47.627807Z","time spent":"942.010449ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T14:05:48.569916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.102038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-29T14:05:48.569979Z","caller":"traceutil/trace.go:171","msg":"trace[616236090] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1324; }","duration":"828.202122ms","start":"2026-05-29T14:05:47.741767Z","end":"2026-05-29T14:05:48.569969Z","steps":["trace[616236090] 'agreement among raft nodes before linearized reading' (duration: 826.363334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.570006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:47.741748Z","time spent":"828.251853ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-29T14:05:48.56884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.015116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:05:48.570099Z","caller":"traceutil/trace.go:171","msg":"trace[529919227] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1324; }","duration":"522.309276ms","start":"2026-05-29T14:05:48.047781Z","end":"2026-05-29T14:05:48.570091Z","steps":["trace[529919227] 'agreement among raft nodes before linearized reading' (duration: 521.030976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.570145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:48.047764Z","time spent":"522.373239ms","remote":"127.0.0.1:32982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-29T14:05:48.568508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.156582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:05:48.56908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.676318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:119565"} {"level":"info","ts":"2026-05-29T14:05:48.570645Z","caller":"traceutil/trace.go:171","msg":"trace[1015574429] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1324; }","duration":"594.273188ms","start":"2026-05-29T14:05:47.976362Z","end":"2026-05-29T14:05:48.570635Z","steps":["trace[1015574429] 'agreement among raft nodes before linearized reading' (duration: 592.612336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.573223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:47.976342Z","time spent":"596.84451ms","remote":"127.0.0.1:51394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":119588,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-05-29T14:05:48.570278Z","caller":"traceutil/trace.go:171","msg":"trace[79987746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"516.973779ms","start":"2026-05-29T14:05:48.053296Z","end":"2026-05-29T14:05:48.57027Z","steps":["trace[79987746] 'agreement among raft nodes before linearized reading' (duration: 515.157472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:48.573876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:48.053275Z","time spent":"520.592333ms","remote":"127.0.0.1:51360","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-29T14:09:41.945732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.612958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:09:41.945999Z","caller":"traceutil/trace.go:171","msg":"trace[596332834] linearizableReadLoop","detail":"{readStateIndex:2531; appliedIndex:2530; }","duration":"257.828788ms","start":"2026-05-29T14:09:41.688157Z","end":"2026-05-29T14:09:41.945985Z","steps":["trace[596332834] 'read index received' (duration: 80.831998ms)","trace[596332834] 'applied index is now lower than readState.Index' (duration: 176.995659ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:09:41.946055Z","caller":"traceutil/trace.go:171","msg":"trace[2108471629] transaction","detail":"{read_only:false; response_revision:2427; number_of_response:1; }","duration":"258.86194ms","start":"2026-05-29T14:09:41.687137Z","end":"2026-05-29T14:09:41.945999Z","steps":["trace[2108471629] 'process raft request' (duration: 81.907532ms)","trace[2108471629] 'compare' (duration: 176.513754ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:09:41.946169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.010123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T14:09:41.946203Z","caller":"traceutil/trace.go:171","msg":"trace[48837031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2427; }","duration":"258.067694ms","start":"2026-05-29T14:09:41.688128Z","end":"2026-05-29T14:09:41.946195Z","steps":["trace[48837031] 'agreement among raft nodes before linearized reading' (duration: 257.953801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:09:41.946605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.399065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-29T14:09:41.946659Z","caller":"traceutil/trace.go:171","msg":"trace[1085468171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2427; }","duration":"258.473627ms","start":"2026-05-29T14:09:41.688174Z","end":"2026-05-29T14:09:41.946648Z","steps":["trace[1085468171] 'agreement among raft nodes before linearized reading' (duration: 258.135417ms)"],"step_count":1}