{"level":"warn","ts":"2026-07-08T09:52:01.714057Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T09:52:01.714158Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.11:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.11:2380","--initial-cluster=instance=https://199.19.213.11:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.11:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.11:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-08T09:52:01.714237Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T09:52:01.714251Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-07-08T09:52:01.714268Z","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-07-08T09:52:01.714918Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"]} {"level":"info","ts":"2026-07-08T09:52:01.714997Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.11:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-08T09:52:01.723905Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.70189ms"} {"level":"info","ts":"2026-07-08T09:52:01.732273Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ce8ad90e35537ee3","cluster-id":"3c17b62c6529d987"} {"level":"info","ts":"2026-07-08T09:52:01.732361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T09:52:01.732386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 0"} {"level":"info","ts":"2026-07-08T09:52:01.7324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ce8ad90e35537ee3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T09:52:01.732409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 1"} {"level":"info","ts":"2026-07-08T09:52:01.732448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"warn","ts":"2026-07-08T09:52:01.740375Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T09:52:01.741618Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T09:52:01.743139Z","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-07-08T09:52:01.747002Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ce8ad90e35537ee3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T09:52:01.748367Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ce8ad90e35537ee3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T09:52:01.749934Z","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-07-08T09:52:01.750002Z","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-07-08T09:52:01.750015Z","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-07-08T09:52:01.752836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"info","ts":"2026-07-08T09:52:01.752947Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","added-peer-id":"ce8ad90e35537ee3","added-peer-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-07-08T09:52:01.754785Z","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-07-08T09:52:01.75501Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-07-08T09:52:01.75506Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-07-08T09:52:01.755226Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ce8ad90e35537ee3","initial-advertise-peer-urls":["https://199.19.213.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T09:52:01.755288Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T09:52:01.935424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T09:52:01.935472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T09:52:01.935486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgPreVoteResp from ce8ad90e35537ee3 at term 1"} {"level":"info","ts":"2026-07-08T09:52:01.935497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became candidate at term 2"} {"level":"info","ts":"2026-07-08T09:52:01.935503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgVoteResp from ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-07-08T09:52:01.935512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became leader at term 2"} {"level":"info","ts":"2026-07-08T09:52:01.935523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ce8ad90e35537ee3 elected leader ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-07-08T09:52:01.937703Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T09:52:01.938756Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ce8ad90e35537ee3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.11:2379]}","request-path":"/0/members/ce8ad90e35537ee3/attributes","cluster-id":"3c17b62c6529d987","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T09:52:01.938987Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T09:52:01.939404Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T09:52:01.939872Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T09:52:01.940058Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T09:52:01.942253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.11:2379"} {"level":"info","ts":"2026-07-08T09:52:01.942403Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T09:52:01.942546Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T09:52:01.942584Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T09:52:01.945299Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T09:52:03.669146Z","caller":"traceutil/trace.go:171","msg":"trace[354892907] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"198.261895ms","start":"2026-07-08T09:52:03.470842Z","end":"2026-07-08T09:52:03.669103Z","steps":["trace[354892907] 'process raft request' (duration: 192.708478ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:03.892363Z","caller":"traceutil/trace.go:171","msg":"trace[1652554508] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"343.23917ms","start":"2026-07-08T09:52:03.549097Z","end":"2026-07-08T09:52:03.892336Z","steps":["trace[1652554508] 'process raft request' (duration: 342.982443ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:03.892447Z","caller":"traceutil/trace.go:171","msg":"trace[1122724203] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"342.825269ms","start":"2026-07-08T09:52:03.549567Z","end":"2026-07-08T09:52:03.892392Z","steps":["trace[1122724203] 'process raft request' (duration: 342.665894ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:03.892603Z","caller":"traceutil/trace.go:171","msg":"trace[1890372094] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"343.450606ms","start":"2026-07-08T09:52:03.549145Z","end":"2026-07-08T09:52:03.892595Z","steps":["trace[1890372094] 'process raft request' (duration: 342.968233ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:03.892559Z","caller":"traceutil/trace.go:171","msg":"trace[1788635649] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"334.138138ms","start":"2026-07-08T09:52:03.558359Z","end":"2026-07-08T09:52:03.892497Z","steps":["trace[1788635649] 'process raft request' (duration: 333.909392ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:03.892661Z","caller":"traceutil/trace.go:171","msg":"trace[1091881285] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"343.389274ms","start":"2026-07-08T09:52:03.549256Z","end":"2026-07-08T09:52:03.892646Z","steps":["trace[1091881285] 'process raft request' (duration: 342.932012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:03.892643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.127235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-07-08T09:52:03.892698Z","caller":"traceutil/trace.go:171","msg":"trace[1330347442] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"343.308401ms","start":"2026-07-08T09:52:03.549383Z","end":"2026-07-08T09:52:03.892691Z","steps":["trace[1330347442] 'process raft request' (duration: 342.828179ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:03.89273Z","caller":"traceutil/trace.go:171","msg":"trace[1860729588] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"414.257418ms","start":"2026-07-08T09:52:03.478458Z","end":"2026-07-08T09:52:03.892716Z","steps":["trace[1860729588] 'agreement among raft nodes before linearized reading' (duration: 414.015562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:03.892774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.478448Z","time spent":"414.31371ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-08T09:52:03.89296Z","caller":"traceutil/trace.go:171","msg":"trace[1391269847] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"333.884452ms","start":"2026-07-08T09:52:03.559057Z","end":"2026-07-08T09:52:03.892942Z","steps":["trace[1391269847] 'process raft request' (duration: 333.242625ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:03.89298Z","caller":"traceutil/trace.go:171","msg":"trace[228526497] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"422.063135ms","start":"2026-07-08T09:52:03.470905Z","end":"2026-07-08T09:52:03.892968Z","steps":["trace[228526497] 'process raft request' (duration: 420.947015ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:03.892937Z","caller":"traceutil/trace.go:171","msg":"trace[1271169325] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"344.054111ms","start":"2026-07-08T09:52:03.548868Z","end":"2026-07-08T09:52:03.892922Z","steps":["trace[1271169325] 'process raft request' (duration: 343.163918ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:03.892405Z","caller":"traceutil/trace.go:171","msg":"trace[803508092] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"413.846618ms","start":"2026-07-08T09:52:03.478486Z","end":"2026-07-08T09:52:03.892332Z","steps":["trace[803508092] 'read index received' (duration: 185.177199ms)","trace[803508092] 'applied index is now lower than readState.Index' (duration: 228.668259ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:52:03.89319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.549374Z","time spent":"343.346543ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:52:03.89323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.073345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T09:52:03.893264Z","caller":"traceutil/trace.go:171","msg":"trace[2105774022] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"312.149367ms","start":"2026-07-08T09:52:03.581104Z","end":"2026-07-08T09:52:03.893253Z","steps":["trace[2105774022] 'agreement among raft nodes before linearized reading' (duration: 312.064555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:03.893289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.581092Z","time spent":"312.191068ms","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-08T09:52:03.893261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.793846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T09:52:03.893353Z","caller":"traceutil/trace.go:171","msg":"trace[232845628] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"413.90966ms","start":"2026-07-08T09:52:03.479413Z","end":"2026-07-08T09:52:03.893323Z","steps":["trace[232845628] 'agreement among raft nodes before linearized reading' (duration: 413.753266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:03.893398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.479403Z","time spent":"413.986092ms","remote":"127.0.0.1:45840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-07-08T09:52:03.893426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.369664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T09:52:03.893449Z","caller":"traceutil/trace.go:171","msg":"trace[790605899] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"275.407645ms","start":"2026-07-08T09:52:03.618031Z","end":"2026-07-08T09:52:03.893439Z","steps":["trace[790605899] 'agreement among raft nodes before linearized reading' (duration: 275.377004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:03.893417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.559049Z","time spent":"333.943923ms","remote":"127.0.0.1:45702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:52:03.893613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.774052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T09:52:03.893653Z","caller":"traceutil/trace.go:171","msg":"trace[1707168737] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"335.835744ms","start":"2026-07-08T09:52:03.557803Z","end":"2026-07-08T09:52:03.893639Z","steps":["trace[1707168737] 'agreement among raft nodes before linearized reading' (duration: 335.783763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:03.893661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.502795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.11\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T09:52:03.893686Z","caller":"traceutil/trace.go:171","msg":"trace[2062826829] range","detail":"{range_begin:/registry/masterleases/199.19.213.11; range_end:; response_count:0; response_revision:11; }","duration":"366.556306ms","start":"2026-07-08T09:52:03.527123Z","end":"2026-07-08T09:52:03.89368Z","steps":["trace[2062826829] 'agreement among raft nodes before linearized reading' (duration: 366.519825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:03.893683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.557785Z","time spent":"335.891195ms","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-07-08T09:52:03.893703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.527096Z","time spent":"366.603978ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.11\" "} {"level":"warn","ts":"2026-07-08T09:52:03.893749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.549132Z","time spent":"343.501227ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:52:03.893833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.095401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T09:52:03.893855Z","caller":"traceutil/trace.go:171","msg":"trace[1866834932] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"344.157283ms","start":"2026-07-08T09:52:03.549692Z","end":"2026-07-08T09:52:03.893849Z","steps":["trace[1866834932] 'agreement among raft nodes before linearized reading' (duration: 344.118772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:03.893872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.549642Z","time spent":"344.226855ms","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-08T09:52:03.894212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.549223Z","time spent":"343.457886ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:52:03.894231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.470888Z","time spent":"422.118297ms","remote":"127.0.0.1:45532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:52:03.894285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.558343Z","time spent":"334.317923ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:52:03.894444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.548841Z","time spent":"344.184854ms","remote":"127.0.0.1:45896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:52:03.894497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.54909Z","time spent":"343.421185ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:52:03.894613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.549524Z","time spent":"343.039265ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:52:04.401472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621891714,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T09:52:04.90223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621891714,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T09:52:05.193964Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.294498903s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-08T09:52:05.75906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.261466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-08T09:52:05.759229Z","caller":"traceutil/trace.go:171","msg":"trace[711951467] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.859857119s","start":"2026-07-08T09:52:03.899351Z","end":"2026-07-08T09:52:05.759208Z","steps":["trace[711951467] 'process raft request' (duration: 1.295052449s)","trace[711951467] 'compare' (duration: 564.03327ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:52:05.7593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.899335Z","time spent":"1.859935951s","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T09:52:05.769593Z","caller":"traceutil/trace.go:171","msg":"trace[89484878] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.869443692s","start":"2026-07-08T09:52:03.900097Z","end":"2026-07-08T09:52:05.769541Z","steps":["trace[89484878] 'process raft request' (duration: 1.869271837s)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:05.769671Z","caller":"traceutil/trace.go:171","msg":"trace[210154148] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.869409691s","start":"2026-07-08T09:52:03.900214Z","end":"2026-07-08T09:52:05.769624Z","steps":["trace[210154148] 'process raft request' (duration: 1.869259997s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.769775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.900072Z","time spent":"1.869616086s","remote":"127.0.0.1:45896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:52:05.769821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.9002Z","time spent":"1.869543575s","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T09:52:05.769825Z","caller":"traceutil/trace.go:171","msg":"trace[2142200826] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"1.869121864s","start":"2026-07-08T09:52:03.900685Z","end":"2026-07-08T09:52:05.769807Z","steps":["trace[2142200826] 'read index received' (duration: 1.293735873s)","trace[2142200826] 'applied index is now lower than readState.Index' (duration: 575.38393ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:52:05.769869Z","caller":"traceutil/trace.go:171","msg":"trace[2115466031] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"1.868693683s","start":"2026-07-08T09:52:03.901158Z","end":"2026-07-08T09:52:05.769852Z","steps":["trace[2115466031] 'process raft request' (duration: 1.868477587s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.769927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.901092Z","time spent":"1.868808695s","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T09:52:05.770026Z","caller":"traceutil/trace.go:171","msg":"trace[990754167] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"1.86783039s","start":"2026-07-08T09:52:03.902186Z","end":"2026-07-08T09:52:05.770016Z","steps":["trace[990754167] 'process raft request' (duration: 1.867518982s)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:05.770068Z","caller":"traceutil/trace.go:171","msg":"trace[1960286114] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.869711718s","start":"2026-07-08T09:52:03.900285Z","end":"2026-07-08T09:52:05.769997Z","steps":["trace[1960286114] 'process raft request' (duration: 1.869233006s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.770087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.902175Z","time spent":"1.867875161s","remote":"127.0.0.1:45840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:52:05.770111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.900268Z","time spent":"1.869819832s","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T09:52:05.770137Z","caller":"traceutil/trace.go:171","msg":"trace[1857056002] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"1.869771021s","start":"2026-07-08T09:52:03.900353Z","end":"2026-07-08T09:52:05.770124Z","steps":["trace[1857056002] 'process raft request' (duration: 1.869228297s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.770186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.900323Z","time spent":"1.869836192s","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T09:52:05.77019Z","caller":"traceutil/trace.go:171","msg":"trace[93157950] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.869884143s","start":"2026-07-08T09:52:03.900294Z","end":"2026-07-08T09:52:05.770178Z","steps":["trace[93157950] 'process raft request' (duration: 1.869255557s)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:05.770203Z","caller":"traceutil/trace.go:171","msg":"trace[1483235585] transaction","detail":"{read_only:false; number_of_response:0; response_revision:17; }","duration":"1.869838133s","start":"2026-07-08T09:52:03.900354Z","end":"2026-07-08T09:52:05.770192Z","steps":["trace[1483235585] 'process raft request' (duration: 1.869257597s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.770243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.900323Z","time spent":"1.869897833s","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:52:05.770247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.900284Z","time spent":"1.869935534s","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T09:52:05.770323Z","caller":"traceutil/trace.go:171","msg":"trace[1512319993] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"1.861021869s","start":"2026-07-08T09:52:03.909288Z","end":"2026-07-08T09:52:05.77031Z","steps":["trace[1512319993] 'process raft request' (duration: 1.860462434s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.770383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.909278Z","time spent":"1.861066691s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:52:05.770416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.869711269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-dw5zc\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-07-08T09:52:05.770452Z","caller":"traceutil/trace.go:171","msg":"trace[219376968] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-dw5zc; range_end:; response_count:1; response_revision:20; }","duration":"1.869790711s","start":"2026-07-08T09:52:03.900651Z","end":"2026-07-08T09:52:05.770442Z","steps":["trace[219376968] 'agreement among raft nodes before linearized reading' (duration: 1.869683378s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.770477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.900638Z","time spent":"1.869833732s","remote":"127.0.0.1:45702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-dw5zc\" "} {"level":"warn","ts":"2026-07-08T09:52:05.770478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.817775236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T09:52:05.770509Z","caller":"traceutil/trace.go:171","msg":"trace[1880337574] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"1.817852398s","start":"2026-07-08T09:52:03.952647Z","end":"2026-07-08T09:52:05.7705Z","steps":["trace[1880337574] 'agreement among raft nodes before linearized reading' (duration: 1.817783056s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.770522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343322894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-08T09:52:05.770534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.952626Z","time spent":"1.817902968s","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-07-08T09:52:05.770549Z","caller":"traceutil/trace.go:171","msg":"trace[837022241] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:20; }","duration":"1.343392856s","start":"2026-07-08T09:52:04.427148Z","end":"2026-07-08T09:52:05.770541Z","steps":["trace[837022241] 'agreement among raft nodes before linearized reading' (duration: 1.343347084s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.770574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:04.427121Z","time spent":"1.343450087s","remote":"127.0.0.1:45816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-07-08T09:52:05.770617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.760051398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-07-08T09:52:05.770644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.815029593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-08T09:52:05.770653Z","caller":"traceutil/trace.go:171","msg":"trace[9277920] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"1.76012355s","start":"2026-07-08T09:52:04.010519Z","end":"2026-07-08T09:52:05.770642Z","steps":["trace[9277920] 'agreement among raft nodes before linearized reading' (duration: 1.760062359s)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:05.770666Z","caller":"traceutil/trace.go:171","msg":"trace[570752241] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"1.815092355s","start":"2026-07-08T09:52:03.955566Z","end":"2026-07-08T09:52:05.770658Z","steps":["trace[570752241] 'agreement among raft nodes before linearized reading' (duration: 1.815048134s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.770678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:04.01049Z","time spent":"1.760182661s","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-08T09:52:05.770683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.955473Z","time spent":"1.815205507s","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-08T09:52:05.770711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.817762825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T09:52:05.770741Z","caller":"traceutil/trace.go:171","msg":"trace[869520528] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:0; response_revision:20; }","duration":"1.817792116s","start":"2026-07-08T09:52:03.952941Z","end":"2026-07-08T09:52:05.770733Z","steps":["trace[869520528] 'agreement among raft nodes before linearized reading' (duration: 1.817744094s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.77075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34200534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T09:52:05.77077Z","caller":"traceutil/trace.go:171","msg":"trace[562981360] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:20; }","duration":"1.342071291s","start":"2026-07-08T09:52:04.428691Z","end":"2026-07-08T09:52:05.770763Z","steps":["trace[562981360] 'agreement among raft nodes before linearized reading' (duration: 1.342036001s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.770779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670313975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-08T09:52:05.770769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:03.952852Z","time spent":"1.817911349s","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-node-lease\" "} {"level":"warn","ts":"2026-07-08T09:52:05.770795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:04.428663Z","time spent":"1.342121253s","remote":"127.0.0.1:45792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-07-08T09:52:05.7708Z","caller":"traceutil/trace.go:171","msg":"trace[1602923860] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"1.670495269s","start":"2026-07-08T09:52:04.100297Z","end":"2026-07-08T09:52:05.770792Z","steps":["trace[1602923860] 'agreement among raft nodes before linearized reading' (duration: 1.670462598s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.770818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:04.100263Z","time spent":"1.670550851s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-07-08T09:52:05.770911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267417226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-08T09:52:05.770932Z","caller":"traceutil/trace.go:171","msg":"trace[2066078504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"1.267498239s","start":"2026-07-08T09:52:04.503428Z","end":"2026-07-08T09:52:05.770926Z","steps":["trace[2066078504] 'agreement among raft nodes before linearized reading' (duration: 1.267461218s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:05.77095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:04.503398Z","time spent":"1.26754768s","remote":"127.0.0.1:45474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T09:52:37.548927Z","caller":"traceutil/trace.go:171","msg":"trace[1147173943] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"439.097581ms","start":"2026-07-08T09:52:37.109794Z","end":"2026-07-08T09:52:37.548891Z","steps":["trace[1147173943] 'process raft request' (duration: 438.838474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:37.549085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:37.109776Z","time spent":"439.240564ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:52:40.912091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.430067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:52:40.912414Z","caller":"traceutil/trace.go:171","msg":"trace[1130339195] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"548.26892ms","start":"2026-07-08T09:52:40.364116Z","end":"2026-07-08T09:52:40.912385Z","steps":["trace[1130339195] 'process raft request' (duration: 181.478423ms)","trace[1130339195] 'compare' (duration: 366.13574ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:52:40.912578Z","caller":"traceutil/trace.go:171","msg":"trace[910979613] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"538.575572ms","start":"2026-07-08T09:52:40.373981Z","end":"2026-07-08T09:52:40.912557Z","steps":["trace[910979613] 'process raft request' (duration: 538.267304ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:40.912715Z","caller":"traceutil/trace.go:171","msg":"trace[1220612106] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"531.648829ms","start":"2026-07-08T09:52:40.381032Z","end":"2026-07-08T09:52:40.912681Z","steps":["trace[1220612106] 'process raft request' (duration: 531.318101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:40.912738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:40.373952Z","time spent":"538.706846ms","remote":"127.0.0.1:45506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:52:40.912852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:40.381015Z","time spent":"531.780002ms","remote":"127.0.0.1:45506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:52:40.912585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:40.364097Z","time spent":"548.397193ms","remote":"127.0.0.1:45718","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-07-08T09:52:40.91313Z","caller":"traceutil/trace.go:171","msg":"trace[133054592] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"524.568542ms","start":"2026-07-08T09:52:40.388549Z","end":"2026-07-08T09:52:40.913118Z","steps":["trace[133054592] 'process raft request' (duration: 523.925935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:40.913195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:40.38851Z","time spent":"524.654624ms","remote":"127.0.0.1:45506","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-07-08T09:52:47.434098Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621892968,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T09:52:47.555093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:46.780012Z","time spent":"775.073832ms","remote":"127.0.0.1:52452","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-08T09:52:47.589271Z","caller":"traceutil/trace.go:171","msg":"trace[1239834020] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"655.730943ms","start":"2026-07-08T09:52:46.933508Z","end":"2026-07-08T09:52:47.589239Z","steps":["trace[1239834020] 'read index received' (duration: 654.929903ms)","trace[1239834020] 'applied index is now lower than readState.Index' (duration: 800.39µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:52:47.58935Z","caller":"traceutil/trace.go:171","msg":"trace[873116481] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"787.466559ms","start":"2026-07-08T09:52:46.801849Z","end":"2026-07-08T09:52:47.589316Z","steps":["trace[873116481] 'process raft request' (duration: 786.849743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:47.589453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.941049ms","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-07-08T09:52:47.589481Z","caller":"traceutil/trace.go:171","msg":"trace[1085885589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"655.98262ms","start":"2026-07-08T09:52:46.933489Z","end":"2026-07-08T09:52:47.589472Z","steps":["trace[1085885589] 'agreement among raft nodes before linearized reading' (duration: 655.884827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:47.589509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:46.801823Z","time spent":"787.595672ms","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:52:47.589519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:46.93341Z","time spent":"656.103123ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T09:52:47.589763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.713207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:52:47.589783Z","caller":"traceutil/trace.go:171","msg":"trace[2066312850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"136.769728ms","start":"2026-07-08T09:52:47.453006Z","end":"2026-07-08T09:52:47.589776Z","steps":["trace[2066312850] 'agreement among raft nodes before linearized reading' (duration: 136.735928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:48.714478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.548856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:52:48.714781Z","caller":"traceutil/trace.go:171","msg":"trace[92797434] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.11737522s","start":"2026-07-08T09:52:47.597386Z","end":"2026-07-08T09:52:48.714761Z","steps":["trace[92797434] 'process raft request' (duration: 1.003415283s)","trace[92797434] 'compare' (duration: 112.989161ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:52:48.714848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:47.597377Z","time spent":"1.117441271s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:52:48.71514Z","caller":"traceutil/trace.go:171","msg":"trace[490765598] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.108261379s","start":"2026-07-08T09:52:47.606869Z","end":"2026-07-08T09:52:48.715131Z","steps":["trace[490765598] 'process raft request' (duration: 1.107855508s)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:48.715298Z","caller":"traceutil/trace.go:171","msg":"trace[1245755061] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.072746828s","start":"2026-07-08T09:52:47.64253Z","end":"2026-07-08T09:52:48.715276Z","steps":["trace[1245755061] 'process raft request' (duration: 1.072554403s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:48.715339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:47.606851Z","time spent":"1.108465784s","remote":"127.0.0.1:45640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:52:48.715219Z","caller":"traceutil/trace.go:171","msg":"trace[296045725] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:659; }","duration":"471.917579ms","start":"2026-07-08T09:52:48.243294Z","end":"2026-07-08T09:52:48.715212Z","steps":["trace[296045725] 'read index received' (duration: 357.599863ms)","trace[296045725] 'applied index is now lower than readState.Index' (duration: 114.316546ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:52:48.715395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:47.642501Z","time spent":"1.072848431s","remote":"127.0.0.1:45940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:52:48.715282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.9848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-08T09:52:48.715492Z","caller":"traceutil/trace.go:171","msg":"trace[1046221750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"472.206366ms","start":"2026-07-08T09:52:48.243261Z","end":"2026-07-08T09:52:48.715467Z","steps":["trace[1046221750] 'agreement among raft nodes before linearized reading' (duration: 471.97109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:48.715524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.000456ms","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-07-08T09:52:48.715552Z","caller":"traceutil/trace.go:171","msg":"trace[2112891131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"465.059177ms","start":"2026-07-08T09:52:48.250483Z","end":"2026-07-08T09:52:48.715542Z","steps":["trace[2112891131] 'agreement among raft nodes before linearized reading' (duration: 465.012756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:48.715572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:48.250463Z","time spent":"465.104458ms","remote":"127.0.0.1:45718","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-07-08T09:52:48.715578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:48.243246Z","time spent":"472.301408ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T09:52:49.952717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621892982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T09:52:50.453316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621892982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T09:52:50.954205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621892982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T09:52:50.961774Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.789940748s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T09:52:50.962806Z","caller":"traceutil/trace.go:171","msg":"trace[1595940795] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.791717735s","start":"2026-07-08T09:52:49.171066Z","end":"2026-07-08T09:52:50.962783Z","steps":["trace[1595940795] 'process raft request' (duration: 1.7911553s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:50.962931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:49.171045Z","time spent":"1.791806616s","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:52:50.984421Z","caller":"traceutil/trace.go:171","msg":"trace[1219950686] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"1.531835428s","start":"2026-07-08T09:52:49.452566Z","end":"2026-07-08T09:52:50.984401Z","steps":["trace[1219950686] 'read index received' (duration: 1.509500957s)","trace[1219950686] 'applied index is now lower than readState.Index' (duration: 22.333631ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:52:50.984655Z","caller":"traceutil/trace.go:171","msg":"trace[1688105315] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.693138045s","start":"2026-07-08T09:52:49.291508Z","end":"2026-07-08T09:52:50.984646Z","steps":["trace[1688105315] 'process raft request' (duration: 1.692787466s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:50.984727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:49.291472Z","time spent":"1.693201407s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:52:50.984848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.53227979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:52:50.984867Z","caller":"traceutil/trace.go:171","msg":"trace[1884151807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"1.53232061s","start":"2026-07-08T09:52:49.45254Z","end":"2026-07-08T09:52:50.984861Z","steps":["trace[1884151807] 'agreement among raft nodes before linearized reading' (duration: 1.532288439s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:50.984882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:49.452518Z","time spent":"1.532360222s","remote":"127.0.0.1:45474","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-07-08T09:52:50.985027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349477951s","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-07-08T09:52:50.985048Z","caller":"traceutil/trace.go:171","msg":"trace[2096350165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"1.349534293s","start":"2026-07-08T09:52:49.635508Z","end":"2026-07-08T09:52:50.985043Z","steps":["trace[2096350165] 'agreement among raft nodes before linearized reading' (duration: 1.349450251s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:50.985063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:49.635489Z","time spent":"1.349570073s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T09:52:50.985209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.155283ms","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-07-08T09:52:50.985243Z","caller":"traceutil/trace.go:171","msg":"trace[1837859123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:644; }","duration":"235.219865ms","start":"2026-07-08T09:52:50.750007Z","end":"2026-07-08T09:52:50.985227Z","steps":["trace[1837859123] 'agreement among raft nodes before linearized reading' (duration: 235.162343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:50.985362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.191051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-08T09:52:50.985386Z","caller":"traceutil/trace.go:171","msg":"trace[1362780864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"247.244004ms","start":"2026-07-08T09:52:50.738136Z","end":"2026-07-08T09:52:50.98538Z","steps":["trace[1362780864] 'agreement among raft nodes before linearized reading' (duration: 247.182341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:50.985492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.343659ms","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-07-08T09:52:50.985516Z","caller":"traceutil/trace.go:171","msg":"trace[11694411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"266.395891ms","start":"2026-07-08T09:52:50.719115Z","end":"2026-07-08T09:52:50.985511Z","steps":["trace[11694411] 'agreement among raft nodes before linearized reading' (duration: 266.33942ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:52:53.040079Z","caller":"traceutil/trace.go:171","msg":"trace[1769684010] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"328.538984ms","start":"2026-07-08T09:52:52.711519Z","end":"2026-07-08T09:52:53.040058Z","steps":["trace[1769684010] 'process raft request' (duration: 328.424211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:53.040232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:52:52.711498Z","time spent":"328.675327ms","remote":"127.0.0.1:45506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T09:52:53.044429Z","caller":"traceutil/trace.go:171","msg":"trace[521125051] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"130.186466ms","start":"2026-07-08T09:52:52.914219Z","end":"2026-07-08T09:52:53.044406Z","steps":["trace[521125051] 'process raft request' (duration: 129.981289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:52:55.918326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.61512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-07-08T09:52:55.918402Z","caller":"traceutil/trace.go:171","msg":"trace[316941351] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:720; }","duration":"208.734983ms","start":"2026-07-08T09:52:55.709647Z","end":"2026-07-08T09:52:55.918382Z","steps":["trace[316941351] 'range keys from in-memory index tree' (duration: 208.457106ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:03.458338Z","caller":"traceutil/trace.go:171","msg":"trace[721196387] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"353.190427ms","start":"2026-07-08T09:53:03.105123Z","end":"2026-07-08T09:53:03.458313Z","steps":["trace[721196387] 'process raft request' (duration: 353.059793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:03.45852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:03.105106Z","time spent":"353.33378ms","remote":"127.0.0.1:45718","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-07-08T09:53:03.46132Z","caller":"traceutil/trace.go:171","msg":"trace[1913817008] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"355.61309ms","start":"2026-07-08T09:53:03.105674Z","end":"2026-07-08T09:53:03.461288Z","steps":["trace[1913817008] 'process raft request' (duration: 355.500857ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:03.461322Z","caller":"traceutil/trace.go:171","msg":"trace[1624406686] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"353.573577ms","start":"2026-07-08T09:53:03.107725Z","end":"2026-07-08T09:53:03.461298Z","steps":["trace[1624406686] 'process raft request' (duration: 353.488914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:03.461489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:03.10565Z","time spent":"355.764445ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:53:03.461508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:03.107709Z","time spent":"353.72007ms","remote":"127.0.0.1:45718","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-07-08T09:53:03.461314Z","caller":"traceutil/trace.go:171","msg":"trace[1947331623] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"355.851147ms","start":"2026-07-08T09:53:03.105449Z","end":"2026-07-08T09:53:03.4613Z","steps":["trace[1947331623] 'process raft request' (duration: 355.623111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:03.461577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:03.10543Z","time spent":"356.120584ms","remote":"127.0.0.1:45718","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-07-08T09:53:06.92376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319822757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-s6qnq\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-08T09:53:06.923826Z","caller":"traceutil/trace.go:171","msg":"trace[2001428555] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-s6qnq; range_end:; response_count:1; response_revision:798; }","duration":"1.31993059s","start":"2026-07-08T09:53:05.603883Z","end":"2026-07-08T09:53:06.923813Z","steps":["trace[2001428555] 'range keys from in-memory index tree' (duration: 1.319670123s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:06.923871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:05.603866Z","time spent":"1.319993161s","remote":"127.0.0.1:45640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-s6qnq\" "} {"level":"warn","ts":"2026-07-08T09:53:06.92376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.367154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:53:06.924056Z","caller":"traceutil/trace.go:171","msg":"trace[1469371389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"471.706443ms","start":"2026-07-08T09:53:06.452342Z","end":"2026-07-08T09:53:06.924048Z","steps":["trace[1469371389] 'range keys from in-memory index tree' (duration: 471.297382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:06.924117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:06.452326Z","time spent":"471.783535ms","remote":"127.0.0.1:45474","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-07-08T09:53:06.924357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.219268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T09:53:06.924452Z","caller":"traceutil/trace.go:171","msg":"trace[65346756] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"551.413393ms","start":"2026-07-08T09:53:06.373012Z","end":"2026-07-08T09:53:06.924426Z","steps":["trace[65346756] 'range keys from in-memory index tree' (duration: 550.959321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:06.924529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:06.37299Z","time spent":"551.522015ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T09:53:06.924963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.763742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.11\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-08T09:53:06.925274Z","caller":"traceutil/trace.go:171","msg":"trace[1356336545] range","detail":"{range_begin:/registry/masterleases/199.19.213.11; range_end:; response_count:1; response_revision:798; }","duration":"317.140342ms","start":"2026-07-08T09:53:06.608096Z","end":"2026-07-08T09:53:06.925237Z","steps":["trace[1356336545] 'range keys from in-memory index tree' (duration: 316.420543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:06.925388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:06.608078Z","time spent":"317.280947ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.11\" "} {"level":"info","ts":"2026-07-08T09:53:07.184068Z","caller":"traceutil/trace.go:171","msg":"trace[137964817] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"259.167999ms","start":"2026-07-08T09:53:06.924872Z","end":"2026-07-08T09:53:07.18404Z","steps":["trace[137964817] 'read index received' (duration: 259.027375ms)","trace[137964817] 'applied index is now lower than readState.Index' (duration: 139.653µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:53:07.184094Z","caller":"traceutil/trace.go:171","msg":"trace[1008076678] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"376.01428ms","start":"2026-07-08T09:53:06.808062Z","end":"2026-07-08T09:53:07.184076Z","steps":["trace[1008076678] 'process raft request' (duration: 375.821185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:07.184322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:06.808042Z","time spent":"376.144703ms","remote":"127.0.0.1:45718","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-07-08T09:53:07.184472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.352634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:53:07.184626Z","caller":"traceutil/trace.go:171","msg":"trace[1407471896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"259.736104ms","start":"2026-07-08T09:53:06.924849Z","end":"2026-07-08T09:53:07.184585Z","steps":["trace[1407471896] 'agreement among raft nodes before linearized reading' (duration: 259.328253ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:07.191827Z","caller":"traceutil/trace.go:171","msg":"trace[1627219119] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"262.483546ms","start":"2026-07-08T09:53:06.929329Z","end":"2026-07-08T09:53:07.191813Z","steps":["trace[1627219119] 'process raft request' (duration: 262.128517ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:07.192067Z","caller":"traceutil/trace.go:171","msg":"trace[571080952] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"261.354247ms","start":"2026-07-08T09:53:06.930706Z","end":"2026-07-08T09:53:07.19206Z","steps":["trace[571080952] 'process raft request' (duration: 261.015608ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:21.436633Z","caller":"traceutil/trace.go:171","msg":"trace[1993460654] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"186.192358ms","start":"2026-07-08T09:53:21.250406Z","end":"2026-07-08T09:53:21.436598Z","steps":["trace[1993460654] 'read index received' (duration: 185.945391ms)","trace[1993460654] 'applied index is now lower than readState.Index' (duration: 245.477µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:53:21.436723Z","caller":"traceutil/trace.go:171","msg":"trace[1680897180] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"388.350067ms","start":"2026-07-08T09:53:21.048332Z","end":"2026-07-08T09:53:21.436682Z","steps":["trace[1680897180] 'process raft request' (duration: 388.037018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:21.436937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:21.048316Z","time spent":"388.515631ms","remote":"127.0.0.1:45640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:53:21.436942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.505266ms","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-07-08T09:53:21.437173Z","caller":"traceutil/trace.go:171","msg":"trace[1233975183] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:936; }","duration":"186.809354ms","start":"2026-07-08T09:53:21.250351Z","end":"2026-07-08T09:53:21.43716Z","steps":["trace[1233975183] 'agreement among raft nodes before linearized reading' (duration: 186.398083ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:21.815044Z","caller":"traceutil/trace.go:171","msg":"trace[273861231] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"241.838791ms","start":"2026-07-08T09:53:21.573166Z","end":"2026-07-08T09:53:21.815005Z","steps":["trace[273861231] 'process raft request' (duration: 241.611925ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:21.817933Z","caller":"traceutil/trace.go:171","msg":"trace[786628666] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:974; }","duration":"174.161229ms","start":"2026-07-08T09:53:21.643744Z","end":"2026-07-08T09:53:21.817905Z","steps":["trace[786628666] 'read index received' (duration: 171.051216ms)","trace[786628666] 'applied index is now lower than readState.Index' (duration: 3.108683ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:53:21.81819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.389204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-qph6f\" ","response":"range_response_count:1 size:4883"} {"level":"info","ts":"2026-07-08T09:53:21.818333Z","caller":"traceutil/trace.go:171","msg":"trace[896515447] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-qph6f; range_end:; response_count:1; response_revision:947; }","duration":"174.619691ms","start":"2026-07-08T09:53:21.643694Z","end":"2026-07-08T09:53:21.818314Z","steps":["trace[896515447] 'agreement among raft nodes before linearized reading' (duration: 174.321974ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:21.818487Z","caller":"traceutil/trace.go:171","msg":"trace[544710372] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"213.672506ms","start":"2026-07-08T09:53:21.604269Z","end":"2026-07-08T09:53:21.817941Z","steps":["trace[544710372] 'process raft request' (duration: 213.383278ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:21.948983Z","caller":"traceutil/trace.go:171","msg":"trace[366335443] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"120.169129ms","start":"2026-07-08T09:53:21.828784Z","end":"2026-07-08T09:53:21.948953Z","steps":["trace[366335443] 'process raft request' (duration: 119.904752ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:21.971999Z","caller":"traceutil/trace.go:171","msg":"trace[620986339] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"126.067307ms","start":"2026-07-08T09:53:21.845892Z","end":"2026-07-08T09:53:21.971959Z","steps":["trace[620986339] 'process raft request' (duration: 125.644326ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:22.27832Z","caller":"traceutil/trace.go:171","msg":"trace[1935844633] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"250.458557ms","start":"2026-07-08T09:53:22.027839Z","end":"2026-07-08T09:53:22.278298Z","steps":["trace[1935844633] 'process raft request' (duration: 247.706944ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:25.841146Z","caller":"traceutil/trace.go:171","msg":"trace[1379987778] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"163.140337ms","start":"2026-07-08T09:53:25.677986Z","end":"2026-07-08T09:53:25.841127Z","steps":["trace[1379987778] 'process raft request' (duration: 163.015014ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:25.93083Z","caller":"traceutil/trace.go:171","msg":"trace[743725762] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"250.432959ms","start":"2026-07-08T09:53:25.680374Z","end":"2026-07-08T09:53:25.930807Z","steps":["trace[743725762] 'process raft request' (duration: 194.095368ms)","trace[743725762] 'compare' (duration: 56.190137ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:53:26.096518Z","caller":"traceutil/trace.go:171","msg":"trace[1557620125] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"160.720373ms","start":"2026-07-08T09:53:25.935767Z","end":"2026-07-08T09:53:26.096488Z","steps":["trace[1557620125] 'process raft request' (duration: 96.400611ms)","trace[1557620125] 'compare' (duration: 64.105346ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:53:26.791455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.649013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:53:26.791613Z","caller":"traceutil/trace.go:171","msg":"trace[1857092317] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"165.255174ms","start":"2026-07-08T09:53:26.626345Z","end":"2026-07-08T09:53:26.7916Z","steps":["trace[1857092317] 'process raft request' (duration: 30.377334ms)","trace[1857092317] 'compare' (duration: 134.52857ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:53:27.768438Z","caller":"traceutil/trace.go:171","msg":"trace[1366099672] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"174.039525ms","start":"2026-07-08T09:53:27.59436Z","end":"2026-07-08T09:53:27.7684Z","steps":["trace[1366099672] 'process raft request' (duration: 173.743098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:34.177517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.819526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-08T09:53:34.177581Z","caller":"traceutil/trace.go:171","msg":"trace[550177330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:988; }","duration":"125.95268ms","start":"2026-07-08T09:53:34.051612Z","end":"2026-07-08T09:53:34.177565Z","steps":["trace[550177330] 'range keys from in-memory index tree' (duration: 125.632272ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:34.25941Z","caller":"traceutil/trace.go:171","msg":"trace[151427685] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1022; }","duration":"124.846961ms","start":"2026-07-08T09:53:34.134539Z","end":"2026-07-08T09:53:34.259386Z","steps":["trace[151427685] 'read index received' (duration: 124.833191ms)","trace[151427685] 'applied index is now lower than readState.Index' (duration: 11.64µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:53:34.259448Z","caller":"traceutil/trace.go:171","msg":"trace[310137023] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"126.306309ms","start":"2026-07-08T09:53:34.133111Z","end":"2026-07-08T09:53:34.259417Z","steps":["trace[310137023] 'process raft request' (duration: 126.105574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:34.259622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.068067ms","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-07-08T09:53:34.25965Z","caller":"traceutil/trace.go:171","msg":"trace[443819831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:989; }","duration":"125.149329ms","start":"2026-07-08T09:53:34.134492Z","end":"2026-07-08T09:53:34.259641Z","steps":["trace[443819831] 'agreement among raft nodes before linearized reading' (duration: 124.979385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:34.422506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.242444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:53:34.422617Z","caller":"traceutil/trace.go:171","msg":"trace[490064073] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"157.675346ms","start":"2026-07-08T09:53:34.264922Z","end":"2026-07-08T09:53:34.422598Z","steps":["trace[490064073] 'process raft request' (duration: 52.245848ms)","trace[490064073] 'compare' (duration: 105.056708ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:53:42.510213Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621893747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T09:53:43.011261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621893747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T09:53:43.512094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621893747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T09:53:43.719596Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.842405485s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T09:53:43.720216Z","caller":"traceutil/trace.go:171","msg":"trace[681787948] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.843149855s","start":"2026-07-08T09:53:41.877044Z","end":"2026-07-08T09:53:43.720194Z","steps":["trace[681787948] 'process raft request' (duration: 1.843001721s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:43.720371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:41.877019Z","time spent":"1.843276528s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:53:44.380991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.842458ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-08T09:53:44.381384Z","caller":"traceutil/trace.go:171","msg":"trace[1160024782] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"2.189001141s","start":"2026-07-08T09:53:42.192359Z","end":"2026-07-08T09:53:44.38136Z","steps":["trace[1160024782] 'process raft request' (duration: 2.188737704s)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:44.38152Z","caller":"traceutil/trace.go:171","msg":"trace[1320418129] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1049; }","duration":"2.372379455s","start":"2026-07-08T09:53:42.009121Z","end":"2026-07-08T09:53:44.3815Z","steps":["trace[1320418129] 'read index received' (duration: 1.710870408s)","trace[1320418129] 'applied index is now lower than readState.Index' (duration: 661.507437ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:53:44.381514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:42.192337Z","time spent":"2.189103773s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:53:44.381564Z","caller":"traceutil/trace.go:171","msg":"trace[1288297256] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"2.096648617s","start":"2026-07-08T09:53:42.284889Z","end":"2026-07-08T09:53:44.381538Z","steps":["trace[1288297256] 'process raft request' (duration: 2.0964127s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:44.381705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:42.284872Z","time spent":"2.096762869s","remote":"127.0.0.1:45718","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-07-08T09:53:44.381716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.797269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T09:53:44.381726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.087286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-08T09:53:44.38174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.372622162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-08T09:53:44.381765Z","caller":"traceutil/trace.go:171","msg":"trace[409944131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"929.879901ms","start":"2026-07-08T09:53:43.45187Z","end":"2026-07-08T09:53:44.38175Z","steps":["trace[409944131] 'agreement among raft nodes before linearized reading' (duration: 929.811959ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:44.381775Z","caller":"traceutil/trace.go:171","msg":"trace[1757645318] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1018; }","duration":"265.174848ms","start":"2026-07-08T09:53:44.116586Z","end":"2026-07-08T09:53:44.381761Z","steps":["trace[1757645318] 'agreement among raft nodes before linearized reading' (duration: 265.098236ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:53:44.381783Z","caller":"traceutil/trace.go:171","msg":"trace[556863645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"2.372705174s","start":"2026-07-08T09:53:42.009065Z","end":"2026-07-08T09:53:44.38177Z","steps":["trace[556863645] 'agreement among raft nodes before linearized reading' (duration: 2.37255573s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:44.381808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:43.45185Z","time spent":"929.947152ms","remote":"127.0.0.1:45474","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-07-08T09:53:44.381827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:42.009041Z","time spent":"2.372771876s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T09:53:44.381827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.353703663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-08T09:53:44.381898Z","caller":"traceutil/trace.go:171","msg":"trace[1346346799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"2.353812646s","start":"2026-07-08T09:53:42.028067Z","end":"2026-07-08T09:53:44.38188Z","steps":["trace[1346346799] 'agreement among raft nodes before linearized reading' (duration: 2.35361088s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:44.381924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.930282171s","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-07-08T09:53:44.381956Z","caller":"traceutil/trace.go:171","msg":"trace[816889095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"1.930344373s","start":"2026-07-08T09:53:42.451601Z","end":"2026-07-08T09:53:44.381946Z","steps":["trace[816889095] 'agreement among raft nodes before linearized reading' (duration: 1.93021602s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:44.38195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:42.028048Z","time spent":"2.353886807s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T09:53:44.381988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:42.451579Z","time spent":"1.930400234s","remote":"127.0.0.1:45718","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-07-08T09:53:44.382032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.092680732s","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-07-08T09:53:44.382085Z","caller":"traceutil/trace.go:171","msg":"trace[917775525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"2.092746893s","start":"2026-07-08T09:53:42.289319Z","end":"2026-07-08T09:53:44.382066Z","steps":["trace[917775525] 'agreement among raft nodes before linearized reading' (duration: 2.09262255s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:53:44.382144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:53:42.28931Z","time spent":"2.092805016s","remote":"127.0.0.1:45718","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-07-08T09:54:06.251961Z","caller":"traceutil/trace.go:171","msg":"trace[1218159167] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"433.300242ms","start":"2026-07-08T09:54:05.818629Z","end":"2026-07-08T09:54:06.251929Z","steps":["trace[1218159167] 'process raft request' (duration: 433.045916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:06.252299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:05.818608Z","time spent":"433.58858ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:54:06.252561Z","caller":"traceutil/trace.go:171","msg":"trace[1953536368] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1128; }","duration":"287.9294ms","start":"2026-07-08T09:54:05.9646Z","end":"2026-07-08T09:54:06.25253Z","steps":["trace[1953536368] 'read index received' (duration: 287.916839ms)","trace[1953536368] 'applied index is now lower than readState.Index' (duration: 10.501µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:54:06.252695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.080653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:06.25273Z","caller":"traceutil/trace.go:171","msg":"trace[355585471] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1089; }","duration":"288.187086ms","start":"2026-07-08T09:54:05.964532Z","end":"2026-07-08T09:54:06.252719Z","steps":["trace[355585471] 'agreement among raft nodes before linearized reading' (duration: 288.100723ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:06.852902Z","caller":"traceutil/trace.go:171","msg":"trace[565705432] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"363.791451ms","start":"2026-07-08T09:54:06.489089Z","end":"2026-07-08T09:54:06.852881Z","steps":["trace[565705432] 'read index received' (duration: 361.272974ms)","trace[565705432] 'applied index is now lower than readState.Index' (duration: 2.517667ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:06.853131Z","caller":"traceutil/trace.go:171","msg":"trace[302123002] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"395.120697ms","start":"2026-07-08T09:54:06.457998Z","end":"2026-07-08T09:54:06.853119Z","steps":["trace[302123002] 'process raft request' (duration: 392.419736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:06.853221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:06.457979Z","time spent":"395.178648ms","remote":"127.0.0.1:45718","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-07-08T09:54:06.853465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.381747ms","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-07-08T09:54:06.853495Z","caller":"traceutil/trace.go:171","msg":"trace[901978333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"364.446619ms","start":"2026-07-08T09:54:06.489039Z","end":"2026-07-08T09:54:06.853486Z","steps":["trace[901978333] 'agreement among raft nodes before linearized reading' (duration: 364.336696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:06.853522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:06.489019Z","time spent":"364.497129ms","remote":"127.0.0.1:45718","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-07-08T09:54:06.853688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.376158ms","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-07-08T09:54:06.853713Z","caller":"traceutil/trace.go:171","msg":"trace[307214938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"350.420148ms","start":"2026-07-08T09:54:06.503286Z","end":"2026-07-08T09:54:06.853706Z","steps":["trace[307214938] 'agreement among raft nodes before linearized reading' (duration: 350.349907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:06.853737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:06.503276Z","time spent":"350.45643ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T09:54:06.854948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.117417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.11\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-08T09:54:06.855026Z","caller":"traceutil/trace.go:171","msg":"trace[350027494] range","detail":"{range_begin:/registry/masterleases/199.19.213.11; range_end:; response_count:1; response_revision:1090; }","duration":"241.284142ms","start":"2026-07-08T09:54:06.613724Z","end":"2026-07-08T09:54:06.855008Z","steps":["trace[350027494] 'agreement among raft nodes before linearized reading' (duration: 241.066217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:06.855173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.286239ms","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-07-08T09:54:06.855216Z","caller":"traceutil/trace.go:171","msg":"trace[2042790250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"335.35338ms","start":"2026-07-08T09:54:06.51985Z","end":"2026-07-08T09:54:06.855203Z","steps":["trace[2042790250] 'agreement among raft nodes before linearized reading' (duration: 335.234557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:06.855247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:06.519821Z","time spent":"335.419123ms","remote":"127.0.0.1:45718","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-07-08T09:54:06.855263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.364182ms","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-07-08T09:54:06.855343Z","caller":"traceutil/trace.go:171","msg":"trace[675532884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"335.493295ms","start":"2026-07-08T09:54:06.519829Z","end":"2026-07-08T09:54:06.855323Z","steps":["trace[675532884] 'agreement among raft nodes before linearized reading' (duration: 335.220677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:06.855385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:06.519809Z","time spent":"335.567358ms","remote":"127.0.0.1:45718","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-07-08T09:54:07.380417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.466276ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-08T09:54:07.380519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:06.857209Z","time spent":"523.305696ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-08T09:54:07.463572Z","caller":"traceutil/trace.go:171","msg":"trace[67338255] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1129; }","duration":"519.054423ms","start":"2026-07-08T09:54:06.944498Z","end":"2026-07-08T09:54:07.463553Z","steps":["trace[67338255] 'read index received' (duration: 101.369942ms)","trace[67338255] 'applied index is now lower than readState.Index' (duration: 417.683681ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:07.463615Z","caller":"traceutil/trace.go:171","msg":"trace[1570217362] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"602.987307ms","start":"2026-07-08T09:54:06.860586Z","end":"2026-07-08T09:54:07.463574Z","steps":["trace[1570217362] 'process raft request' (duration: 602.769751ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:07.463663Z","caller":"traceutil/trace.go:171","msg":"trace[1835253623] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"602.198755ms","start":"2026-07-08T09:54:06.861455Z","end":"2026-07-08T09:54:07.463653Z","steps":["trace[1835253623] 'process raft request' (duration: 602.027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:07.463744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:06.861438Z","time spent":"602.246187ms","remote":"127.0.0.1:45718","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-07-08T09:54:07.463744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:06.860568Z","time spent":"603.110229ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:54:07.46391Z","caller":"traceutil/trace.go:171","msg":"trace[1440673864] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"601.903537ms","start":"2026-07-08T09:54:06.861987Z","end":"2026-07-08T09:54:07.463891Z","steps":["trace[1440673864] 'process raft request' (duration: 601.539807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:07.463988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.490045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:07.464011Z","caller":"traceutil/trace.go:171","msg":"trace[1219552435] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1094; }","duration":"519.513086ms","start":"2026-07-08T09:54:06.94449Z","end":"2026-07-08T09:54:07.464003Z","steps":["trace[1219552435] 'agreement among raft nodes before linearized reading' (duration: 519.475315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:07.464036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:06.944426Z","time spent":"519.602578ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-08T09:54:07.464108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:06.861973Z","time spent":"601.98023ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:54:07.464242Z","caller":"traceutil/trace.go:171","msg":"trace[1839780283] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"602.583745ms","start":"2026-07-08T09:54:06.861649Z","end":"2026-07-08T09:54:07.464232Z","steps":["trace[1839780283] 'process raft request' (duration: 601.857016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:07.464282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:06.86164Z","time spent":"602.620776ms","remote":"127.0.0.1:45718","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-07-08T09:54:08.701088Z","caller":"traceutil/trace.go:171","msg":"trace[1286263557] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"440.089401ms","start":"2026-07-08T09:54:08.260972Z","end":"2026-07-08T09:54:08.701061Z","steps":["trace[1286263557] 'process raft request' (duration: 439.922387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:08.701262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:08.260946Z","time spent":"440.218355ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:54:09.731894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.943548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:54:09.732011Z","caller":"traceutil/trace.go:171","msg":"trace[1643405845] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"279.090546ms","start":"2026-07-08T09:54:09.4529Z","end":"2026-07-08T09:54:09.731991Z","steps":["trace[1643405845] 'read index received' (duration: 146.862821ms)","trace[1643405845] 'applied index is now lower than readState.Index' (duration: 132.226245ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:09.73211Z","caller":"traceutil/trace.go:171","msg":"trace[71362198] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"869.642623ms","start":"2026-07-08T09:54:08.862435Z","end":"2026-07-08T09:54:09.732078Z","steps":["trace[71362198] 'process raft request' (duration: 737.425018ms)","trace[71362198] 'compare' (duration: 131.822205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:54:09.732139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.24013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:09.732216Z","caller":"traceutil/trace.go:171","msg":"trace[37962995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"279.315202ms","start":"2026-07-08T09:54:09.452886Z","end":"2026-07-08T09:54:09.732201Z","steps":["trace[37962995] 'agreement among raft nodes before linearized reading' (duration: 279.212109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:09.732271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:08.862412Z","time spent":"869.778347ms","remote":"127.0.0.1:45718","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-07-08T09:54:09.732582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.218061ms","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-07-08T09:54:09.732629Z","caller":"traceutil/trace.go:171","msg":"trace[1433873698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"265.353924ms","start":"2026-07-08T09:54:09.467262Z","end":"2026-07-08T09:54:09.732616Z","steps":["trace[1433873698] 'agreement among raft nodes before linearized reading' (duration: 265.19979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:09.732675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.417981ms","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-07-08T09:54:09.732723Z","caller":"traceutil/trace.go:171","msg":"trace[64346274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"264.465512ms","start":"2026-07-08T09:54:09.468246Z","end":"2026-07-08T09:54:09.732712Z","steps":["trace[64346274] 'agreement among raft nodes before linearized reading' (duration: 264.330128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:09.732673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.347218ms","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-07-08T09:54:09.73287Z","caller":"traceutil/trace.go:171","msg":"trace[310846559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"264.583805ms","start":"2026-07-08T09:54:09.468276Z","end":"2026-07-08T09:54:09.73286Z","steps":["trace[310846559] 'agreement among raft nodes before linearized reading' (duration: 264.248436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:09.732886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.198861ms","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-07-08T09:54:09.732933Z","caller":"traceutil/trace.go:171","msg":"trace[1280453278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1098; }","duration":"265.261322ms","start":"2026-07-08T09:54:09.467658Z","end":"2026-07-08T09:54:09.732919Z","steps":["trace[1280453278] 'agreement among raft nodes before linearized reading' (duration: 265.136859ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:09.952986Z","caller":"traceutil/trace.go:171","msg":"trace[1809904972] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"215.336826ms","start":"2026-07-08T09:54:09.737614Z","end":"2026-07-08T09:54:09.952951Z","steps":["trace[1809904972] 'process raft request' (duration: 178.255399ms)","trace[1809904972] 'compare' (duration: 36.920733ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:10.015389Z","caller":"traceutil/trace.go:171","msg":"trace[2028998691] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"277.460974ms","start":"2026-07-08T09:54:09.737904Z","end":"2026-07-08T09:54:10.015365Z","steps":["trace[2028998691] 'process raft request' (duration: 277.386242ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:10.015566Z","caller":"traceutil/trace.go:171","msg":"trace[1262722367] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"277.734252ms","start":"2026-07-08T09:54:09.737822Z","end":"2026-07-08T09:54:10.015556Z","steps":["trace[1262722367] 'process raft request' (duration: 277.348131ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:10.01566Z","caller":"traceutil/trace.go:171","msg":"trace[1923343560] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1138; }","duration":"185.550211ms","start":"2026-07-08T09:54:09.8301Z","end":"2026-07-08T09:54:10.01565Z","steps":["trace[1923343560] 'read index received' (duration: 85.831082ms)","trace[1923343560] 'applied index is now lower than readState.Index' (duration: 99.718359ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:54:10.015797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.675645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:10.015832Z","caller":"traceutil/trace.go:171","msg":"trace[1226776202] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1102; }","duration":"185.727387ms","start":"2026-07-08T09:54:09.830095Z","end":"2026-07-08T09:54:10.015822Z","steps":["trace[1226776202] 'agreement among raft nodes before linearized reading' (duration: 185.591223ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:10.015964Z","caller":"traceutil/trace.go:171","msg":"trace[1411683215] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"277.767852ms","start":"2026-07-08T09:54:09.738167Z","end":"2026-07-08T09:54:10.015935Z","steps":["trace[1411683215] 'process raft request' (duration: 277.158046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:11.250868Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621893970,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T09:54:11.59379Z","caller":"traceutil/trace.go:171","msg":"trace[1430934268] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"843.512856ms","start":"2026-07-08T09:54:10.750259Z","end":"2026-07-08T09:54:11.593772Z","steps":["trace[1430934268] 'read index received' (duration: 843.349111ms)","trace[1430934268] 'applied index is now lower than readState.Index' (duration: 163.115µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:11.59388Z","caller":"traceutil/trace.go:171","msg":"trace[881813454] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"884.059035ms","start":"2026-07-08T09:54:10.709811Z","end":"2026-07-08T09:54:11.59387Z","steps":["trace[881813454] 'process raft request' (duration: 883.838749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:11.593966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:10.709793Z","time spent":"884.105377ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:54:11.594023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.703526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T09:54:11.59408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.328341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:11.594082Z","caller":"traceutil/trace.go:171","msg":"trace[1538400552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"138.805968ms","start":"2026-07-08T09:54:11.45526Z","end":"2026-07-08T09:54:11.594066Z","steps":["trace[1538400552] 'agreement among raft nodes before linearized reading' (duration: 138.709176ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:11.594112Z","caller":"traceutil/trace.go:171","msg":"trace[843525080] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1103; }","duration":"178.395033ms","start":"2026-07-08T09:54:11.415706Z","end":"2026-07-08T09:54:11.594101Z","steps":["trace[843525080] 'agreement among raft nodes before linearized reading' (duration: 178.353432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:11.594269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.81937ms","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-07-08T09:54:11.594306Z","caller":"traceutil/trace.go:171","msg":"trace[748222419] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1103; }","duration":"748.902272ms","start":"2026-07-08T09:54:10.845395Z","end":"2026-07-08T09:54:11.594298Z","steps":["trace[748222419] 'agreement among raft nodes before linearized reading' (duration: 748.849091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:11.594335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:10.845374Z","time spent":"748.950593ms","remote":"127.0.0.1:45724","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-07-08T09:54:11.594273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.015849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:11.594458Z","caller":"traceutil/trace.go:171","msg":"trace[68986691] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1103; }","duration":"844.236745ms","start":"2026-07-08T09:54:10.750211Z","end":"2026-07-08T09:54:11.594448Z","steps":["trace[68986691] 'agreement among raft nodes before linearized reading' (duration: 844.03385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:11.5945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:10.750191Z","time spent":"844.296866ms","remote":"127.0.0.1:45680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-08T09:54:11.594027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.618819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T09:54:11.594603Z","caller":"traceutil/trace.go:171","msg":"trace[769380799] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1103; }","duration":"759.243015ms","start":"2026-07-08T09:54:10.835348Z","end":"2026-07-08T09:54:11.594592Z","steps":["trace[769380799] 'agreement among raft nodes before linearized reading' (duration: 758.623359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:11.594642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:10.835327Z","time spent":"759.303996ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-07-08T09:54:11.983506Z","caller":"traceutil/trace.go:171","msg":"trace[962156590] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"246.907888ms","start":"2026-07-08T09:54:11.736577Z","end":"2026-07-08T09:54:11.983485Z","steps":["trace[962156590] 'process raft request' (duration: 246.762064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:13.049582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.476997ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-08T09:54:13.049665Z","caller":"traceutil/trace.go:171","msg":"trace[1114667459] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1143; }","duration":"1.092827068s","start":"2026-07-08T09:54:11.956824Z","end":"2026-07-08T09:54:13.049651Z","steps":["trace[1114667459] 'read index received' (duration: 26.59214ms)","trace[1114667459] 'applied index is now lower than readState.Index' (duration: 1.066233748s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:54:13.050237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030381432s","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-07-08T09:54:13.05027Z","caller":"traceutil/trace.go:171","msg":"trace[837768549] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1104; }","duration":"1.030463604s","start":"2026-07-08T09:54:12.019798Z","end":"2026-07-08T09:54:13.050262Z","steps":["trace[837768549] 'agreement among raft nodes before linearized reading' (duration: 1.030349111s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:13.05029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:12.019776Z","time spent":"1.030508666s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T09:54:13.05032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025887673s","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-07-08T09:54:13.050354Z","caller":"traceutil/trace.go:171","msg":"trace[29001881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1104; }","duration":"1.025946585s","start":"2026-07-08T09:54:12.024397Z","end":"2026-07-08T09:54:13.050344Z","steps":["trace[29001881] 'agreement among raft nodes before linearized reading' (duration: 1.025837822s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:13.050354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093535866s","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-07-08T09:54:13.050379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:12.024382Z","time spent":"1.025990576s","remote":"127.0.0.1:45718","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-07-08T09:54:13.050381Z","caller":"traceutil/trace.go:171","msg":"trace[1633076083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1104; }","duration":"1.093598238s","start":"2026-07-08T09:54:11.956775Z","end":"2026-07-08T09:54:13.050373Z","steps":["trace[1633076083] 'agreement among raft nodes before linearized reading' (duration: 1.093514986s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:13.050404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:11.956755Z","time spent":"1.09364303s","remote":"127.0.0.1:45718","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-07-08T09:54:13.05041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029042457s","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-07-08T09:54:13.050428Z","caller":"traceutil/trace.go:171","msg":"trace[71939307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1104; }","duration":"1.029083537s","start":"2026-07-08T09:54:12.021339Z","end":"2026-07-08T09:54:13.050422Z","steps":["trace[71939307] 'agreement among raft nodes before linearized reading' (duration: 1.029033356s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:13.050446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:12.021241Z","time spent":"1.029201541s","remote":"127.0.0.1:45718","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-07-08T09:54:27.519057Z","caller":"traceutil/trace.go:171","msg":"trace[396009536] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"374.859401ms","start":"2026-07-08T09:54:27.144175Z","end":"2026-07-08T09:54:27.519034Z","steps":["trace[396009536] 'process raft request' (duration: 374.709007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:27.519191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:27.144166Z","time spent":"374.957253ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:54:27.813736Z","caller":"traceutil/trace.go:171","msg":"trace[494633483] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1248; }","duration":"668.907553ms","start":"2026-07-08T09:54:27.144805Z","end":"2026-07-08T09:54:27.813713Z","steps":["trace[494633483] 'read index received' (duration: 374.109512ms)","trace[494633483] 'applied index is now lower than readState.Index' (duration: 294.79705ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:27.813744Z","caller":"traceutil/trace.go:171","msg":"trace[595669423] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"669.260961ms","start":"2026-07-08T09:54:27.14445Z","end":"2026-07-08T09:54:27.81371Z","steps":["trace[595669423] 'process raft request' (duration: 669.078087ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:27.813838Z","caller":"traceutil/trace.go:171","msg":"trace[1259870191] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"668.81243ms","start":"2026-07-08T09:54:27.145003Z","end":"2026-07-08T09:54:27.813816Z","steps":["trace[1259870191] 'process raft request' (duration: 668.660436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:27.813915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:27.144436Z","time spent":"669.384664ms","remote":"127.0.0.1:45718","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-07-08T09:54:27.813936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:27.144998Z","time spent":"668.890772ms","remote":"127.0.0.1:45718","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-07-08T09:54:27.813958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.858028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:27.814002Z","caller":"traceutil/trace.go:171","msg":"trace[1682835328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1205; }","duration":"361.953521ms","start":"2026-07-08T09:54:27.452037Z","end":"2026-07-08T09:54:27.813991Z","steps":["trace[1682835328] 'agreement among raft nodes before linearized reading' (duration: 361.866258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:27.814045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:27.45202Z","time spent":"362.016472ms","remote":"127.0.0.1:45474","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-07-08T09:54:27.814099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.97821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T09:54:27.814153Z","caller":"traceutil/trace.go:171","msg":"trace[472473571] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1205; }","duration":"151.069713ms","start":"2026-07-08T09:54:27.66307Z","end":"2026-07-08T09:54:27.81414Z","steps":["trace[472473571] 'agreement among raft nodes before linearized reading' (duration: 150.927689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:27.814097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.276392ms","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-07-08T09:54:27.814248Z","caller":"traceutil/trace.go:171","msg":"trace[1826473694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1205; }","duration":"669.451216ms","start":"2026-07-08T09:54:27.144786Z","end":"2026-07-08T09:54:27.814237Z","steps":["trace[1826473694] 'agreement among raft nodes before linearized reading' (duration: 669.151238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:27.814291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:27.144778Z","time spent":"669.502828ms","remote":"127.0.0.1:45718","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-07-08T09:54:30.318935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621894211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T09:54:30.819783Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621894211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T09:54:31.177317Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.649748917s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T09:54:31.17769Z","caller":"traceutil/trace.go:171","msg":"trace[416791250] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"1.650211358s","start":"2026-07-08T09:54:29.527459Z","end":"2026-07-08T09:54:31.17767Z","steps":["trace[416791250] 'process raft request' (duration: 1.650060805s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:31.177817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:29.52744Z","time spent":"1.650308822s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:54:31.889726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.147963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:54:31.88995Z","caller":"traceutil/trace.go:171","msg":"trace[1070887355] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"1.849137322s","start":"2026-07-08T09:54:30.040787Z","end":"2026-07-08T09:54:31.889924Z","steps":["trace[1070887355] 'process raft request' (duration: 1.353706532s)","trace[1070887355] 'compare' (duration: 494.703411ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:31.890041Z","caller":"traceutil/trace.go:171","msg":"trace[684441945] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"2.071866734s","start":"2026-07-08T09:54:29.817931Z","end":"2026-07-08T09:54:31.889798Z","steps":["trace[684441945] 'read index received' (duration: 1.359905357s)","trace[684441945] 'applied index is now lower than readState.Index' (duration: 711.960257ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:54:31.890086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:30.040769Z","time spent":"1.849234735s","remote":"127.0.0.1:45718","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-07-08T09:54:31.890193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.072254054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-08T09:54:31.890248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.072254414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-08T09:54:31.890241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.973219393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:31.890286Z","caller":"traceutil/trace.go:171","msg":"trace[1237170792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"2.072306975s","start":"2026-07-08T09:54:29.817969Z","end":"2026-07-08T09:54:31.890276Z","steps":["trace[1237170792] 'agreement among raft nodes before linearized reading' (duration: 2.072172702s)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:31.890301Z","caller":"traceutil/trace.go:171","msg":"trace[603568153] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1211; }","duration":"1.973299255s","start":"2026-07-08T09:54:29.916986Z","end":"2026-07-08T09:54:31.890285Z","steps":["trace[603568153] 'agreement among raft nodes before linearized reading' (duration: 1.973168441s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:31.890319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:29.817962Z","time spent":"2.072348846s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T09:54:31.890329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.063602566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T09:54:31.890355Z","caller":"traceutil/trace.go:171","msg":"trace[2116865227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"2.063650836s","start":"2026-07-08T09:54:29.826697Z","end":"2026-07-08T09:54:31.890348Z","steps":["trace[2116865227] 'agreement among raft nodes before linearized reading' (duration: 2.063578564s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:31.89035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:29.916919Z","time spent":"1.973423958s","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-08T09:54:31.890395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:29.826686Z","time spent":"2.063697077s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T09:54:31.890445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.066268836s","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-07-08T09:54:31.890251Z","caller":"traceutil/trace.go:171","msg":"trace[1775121473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"2.072350047s","start":"2026-07-08T09:54:29.817891Z","end":"2026-07-08T09:54:31.890241Z","steps":["trace[1775121473] 'agreement among raft nodes before linearized reading' (duration: 2.072149481s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:31.890491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:29.81787Z","time spent":"2.072611204s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T09:54:31.890501Z","caller":"traceutil/trace.go:171","msg":"trace[1637475885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"2.066348917s","start":"2026-07-08T09:54:29.82412Z","end":"2026-07-08T09:54:31.890469Z","steps":["trace[1637475885] 'agreement among raft nodes before linearized reading' (duration: 2.066276706s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:31.89057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.665576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:31.890642Z","caller":"traceutil/trace.go:171","msg":"trace[920037274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"440.8549ms","start":"2026-07-08T09:54:31.449767Z","end":"2026-07-08T09:54:31.890622Z","steps":["trace[920037274] 'agreement among raft nodes before linearized reading' (duration: 440.756888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:31.890708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:29.824108Z","time spent":"2.066589983s","remote":"127.0.0.1:45718","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-07-08T09:54:31.890723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:31.449742Z","time spent":"440.968194ms","remote":"127.0.0.1:45474","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-07-08T09:54:44.174367Z","caller":"traceutil/trace.go:171","msg":"trace[1227751536] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"235.510738ms","start":"2026-07-08T09:54:43.93883Z","end":"2026-07-08T09:54:44.174341Z","steps":["trace[1227751536] 'read index received' (duration: 235.161819ms)","trace[1227751536] 'applied index is now lower than readState.Index' (duration: 347.859µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:44.174576Z","caller":"traceutil/trace.go:171","msg":"trace[1920049515] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"241.268489ms","start":"2026-07-08T09:54:43.933262Z","end":"2026-07-08T09:54:44.174544Z","steps":["trace[1920049515] 'process raft request' (duration: 240.852498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:44.174621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.516202ms","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-07-08T09:54:44.174621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.779735ms","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-07-08T09:54:44.174658Z","caller":"traceutil/trace.go:171","msg":"trace[1964210517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"212.585024ms","start":"2026-07-08T09:54:43.962062Z","end":"2026-07-08T09:54:44.174647Z","steps":["trace[1964210517] 'agreement among raft nodes before linearized reading' (duration: 212.483851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:44.174674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.625405ms","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-07-08T09:54:44.174688Z","caller":"traceutil/trace.go:171","msg":"trace[339385099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"235.882007ms","start":"2026-07-08T09:54:43.93879Z","end":"2026-07-08T09:54:44.174672Z","steps":["trace[339385099] 'agreement among raft nodes before linearized reading' (duration: 235.702073ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:44.174728Z","caller":"traceutil/trace.go:171","msg":"trace[1420442984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1250; }","duration":"212.710967ms","start":"2026-07-08T09:54:43.962002Z","end":"2026-07-08T09:54:44.174713Z","steps":["trace[1420442984] 'agreement among raft nodes before linearized reading' (duration: 212.565443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:44.174714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.888001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T09:54:44.174795Z","caller":"traceutil/trace.go:171","msg":"trace[1971692465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1250; }","duration":"224.031135ms","start":"2026-07-08T09:54:43.950749Z","end":"2026-07-08T09:54:44.17478Z","steps":["trace[1971692465] 'agreement among raft nodes before linearized reading' (duration: 223.741558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:44.174845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.322949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:44.174978Z","caller":"traceutil/trace.go:171","msg":"trace[680052568] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1250; }","duration":"181.522904ms","start":"2026-07-08T09:54:43.993445Z","end":"2026-07-08T09:54:44.174968Z","steps":["trace[680052568] 'agreement among raft nodes before linearized reading' (duration: 181.304178ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:45.725279Z","caller":"traceutil/trace.go:171","msg":"trace[368417688] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"481.832991ms","start":"2026-07-08T09:54:45.243421Z","end":"2026-07-08T09:54:45.725254Z","steps":["trace[368417688] 'process raft request' (duration: 481.676227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:45.725447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:45.243404Z","time spent":"481.946434ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:54:46.505992Z","caller":"traceutil/trace.go:171","msg":"trace[781174777] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1303; }","duration":"1.054090655s","start":"2026-07-08T09:54:45.451876Z","end":"2026-07-08T09:54:46.505967Z","steps":["trace[781174777] 'read index received' (duration: 273.001137ms)","trace[781174777] 'applied index is now lower than readState.Index' (duration: 781.088648ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:46.506375Z","caller":"traceutil/trace.go:171","msg":"trace[922121097] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"1.077106962s","start":"2026-07-08T09:54:45.429232Z","end":"2026-07-08T09:54:46.506339Z","steps":["trace[922121097] 'process raft request' (duration: 1.076623839s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:46.506429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054534218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:46.506491Z","caller":"traceutil/trace.go:171","msg":"trace[1983876045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"1.054650741s","start":"2026-07-08T09:54:45.451825Z","end":"2026-07-08T09:54:46.506476Z","steps":["trace[1983876045] 'agreement among raft nodes before linearized reading' (duration: 1.054522037s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:46.506516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:45.451807Z","time spent":"1.054705293s","remote":"127.0.0.1:45474","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-07-08T09:54:46.506651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:45.429211Z","time spent":"1.077198745s","remote":"127.0.0.1:45718","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-07-08T09:54:46.535429Z","caller":"traceutil/trace.go:171","msg":"trace[1216567956] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"355.091351ms","start":"2026-07-08T09:54:46.180315Z","end":"2026-07-08T09:54:46.535406Z","steps":["trace[1216567956] 'process raft request' (duration: 354.750522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:46.53564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:46.180291Z","time spent":"355.277175ms","remote":"127.0.0.1:45718","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-07-08T09:54:46.535917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.147757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-08T09:54:46.536722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.415016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T09:54:46.536755Z","caller":"traceutil/trace.go:171","msg":"trace[1520188812] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1257; }","duration":"364.453297ms","start":"2026-07-08T09:54:46.172294Z","end":"2026-07-08T09:54:46.536747Z","steps":["trace[1520188812] 'agreement among raft nodes before linearized reading' (duration: 363.685407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:46.536778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:46.172225Z","time spent":"364.547179ms","remote":"127.0.0.1:45786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-07-08T09:54:46.536734Z","caller":"traceutil/trace.go:171","msg":"trace[743675397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1257; }","duration":"264.009979ms","start":"2026-07-08T09:54:46.272708Z","end":"2026-07-08T09:54:46.536718Z","steps":["trace[743675397] 'agreement among raft nodes before linearized reading' (duration: 263.086895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:46.53625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.309407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T09:54:46.536973Z","caller":"traceutil/trace.go:171","msg":"trace[1584821269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"265.072688ms","start":"2026-07-08T09:54:46.271883Z","end":"2026-07-08T09:54:46.536956Z","steps":["trace[1584821269] 'agreement among raft nodes before linearized reading' (duration: 264.259336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:46.536321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.568898ms","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-07-08T09:54:46.537029Z","caller":"traceutil/trace.go:171","msg":"trace[1878365600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"264.279867ms","start":"2026-07-08T09:54:46.272744Z","end":"2026-07-08T09:54:46.537024Z","steps":["trace[1878365600] 'agreement among raft nodes before linearized reading' (duration: 263.527446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:46.536391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.128295ms","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-07-08T09:54:46.537076Z","caller":"traceutil/trace.go:171","msg":"trace[306840381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"340.849995ms","start":"2026-07-08T09:54:46.196222Z","end":"2026-07-08T09:54:46.537072Z","steps":["trace[306840381] 'agreement among raft nodes before linearized reading' (duration: 340.124625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:46.537094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:46.196208Z","time spent":"340.879556ms","remote":"127.0.0.1:45718","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-07-08T09:54:46.675629Z","caller":"traceutil/trace.go:171","msg":"trace[1264013519] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"133.628333ms","start":"2026-07-08T09:54:46.541975Z","end":"2026-07-08T09:54:46.675603Z","steps":["trace[1264013519] 'process raft request' (duration: 133.506519ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:46.675687Z","caller":"traceutil/trace.go:171","msg":"trace[988281732] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1306; }","duration":"132.777999ms","start":"2026-07-08T09:54:46.542896Z","end":"2026-07-08T09:54:46.675674Z","steps":["trace[988281732] 'read index received' (duration: 35.469474ms)","trace[988281732] 'applied index is now lower than readState.Index' (duration: 97.307125ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:46.675764Z","caller":"traceutil/trace.go:171","msg":"trace[365795583] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"133.036206ms","start":"2026-07-08T09:54:46.54271Z","end":"2026-07-08T09:54:46.675746Z","steps":["trace[365795583] 'process raft request' (duration: 132.845411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:46.675817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.907834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:46.675841Z","caller":"traceutil/trace.go:171","msg":"trace[1416940385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"132.965635ms","start":"2026-07-08T09:54:46.542868Z","end":"2026-07-08T09:54:46.675834Z","steps":["trace[1416940385] 'agreement among raft nodes before linearized reading' (duration: 132.894383ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:46.675938Z","caller":"traceutil/trace.go:171","msg":"trace[1839626393] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"134.65756ms","start":"2026-07-08T09:54:46.541262Z","end":"2026-07-08T09:54:46.67592Z","steps":["trace[1839626393] 'process raft request' (duration: 83.572753ms)","trace[1839626393] 'compare' (duration: 50.411949ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:54:48.580603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.251292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-08T09:54:48.58068Z","caller":"traceutil/trace.go:171","msg":"trace[332765194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1263; }","duration":"850.369665ms","start":"2026-07-08T09:54:47.730292Z","end":"2026-07-08T09:54:48.580661Z","steps":["trace[332765194] 'range keys from in-memory index tree' (duration: 850.043066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:48.580721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:47.730273Z","time spent":"850.439126ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T09:54:48.580848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.786715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:48.580951Z","caller":"traceutil/trace.go:171","msg":"trace[936998566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"128.933179ms","start":"2026-07-08T09:54:48.451994Z","end":"2026-07-08T09:54:48.580927Z","steps":["trace[936998566] 'range keys from in-memory index tree' (duration: 128.690982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:49.113493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621894344,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T09:54:49.395019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.7085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:54:49.395173Z","caller":"traceutil/trace.go:171","msg":"trace[1230232681] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1314; }","duration":"782.115906ms","start":"2026-07-08T09:54:48.613041Z","end":"2026-07-08T09:54:49.395156Z","steps":["trace[1230232681] 'read index received' (duration: 589.15677ms)","trace[1230232681] 'applied index is now lower than readState.Index' (duration: 192.958176ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:54:49.39546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.487357ms","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-07-08T09:54:49.395465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.769488ms","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-07-08T09:54:49.395498Z","caller":"traceutil/trace.go:171","msg":"trace[29317209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1266; }","duration":"716.549249ms","start":"2026-07-08T09:54:48.678939Z","end":"2026-07-08T09:54:49.395488Z","steps":["trace[29317209] 'agreement among raft nodes before linearized reading' (duration: 716.447046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:49.395558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:48.678924Z","time spent":"716.59925ms","remote":"127.0.0.1:45718","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-07-08T09:54:49.395611Z","caller":"traceutil/trace.go:171","msg":"trace[635976898] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"810.084463ms","start":"2026-07-08T09:54:48.58551Z","end":"2026-07-08T09:54:49.395594Z","steps":["trace[635976898] 'process raft request' (duration: 616.711056ms)","trace[635976898] 'compare' (duration: 192.600527ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:54:49.395695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:48.585498Z","time spent":"810.156425ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:54:49.395792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.781735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T09:54:49.395834Z","caller":"traceutil/trace.go:171","msg":"trace[1669678171] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1266; }","duration":"301.859277ms","start":"2026-07-08T09:54:49.093964Z","end":"2026-07-08T09:54:49.395824Z","steps":["trace[1669678171] 'agreement among raft nodes before linearized reading' (duration: 301.789776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:49.395872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:49.093944Z","time spent":"301.919648ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-07-08T09:54:49.395875Z","caller":"traceutil/trace.go:171","msg":"trace[1403667470] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"757.654181ms","start":"2026-07-08T09:54:48.638209Z","end":"2026-07-08T09:54:49.395863Z","steps":["trace[1403667470] 'process raft request' (duration: 756.898732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:49.395951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:48.638187Z","time spent":"757.718623ms","remote":"127.0.0.1:45718","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-07-08T09:54:49.396069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.630375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T09:54:49.396105Z","caller":"traceutil/trace.go:171","msg":"trace[1722260714] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1266; }","duration":"712.693045ms","start":"2026-07-08T09:54:48.683404Z","end":"2026-07-08T09:54:49.396097Z","steps":["trace[1722260714] 'agreement among raft nodes before linearized reading' (duration: 712.606793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:49.396137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:48.683388Z","time spent":"712.740397ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T09:54:49.395521Z","caller":"traceutil/trace.go:171","msg":"trace[453982667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1266; }","duration":"811.875701ms","start":"2026-07-08T09:54:48.583629Z","end":"2026-07-08T09:54:49.395505Z","steps":["trace[453982667] 'agreement among raft nodes before linearized reading' (duration: 811.703037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:49.396341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:48.583607Z","time spent":"812.717993ms","remote":"127.0.0.1:45718","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-07-08T09:54:49.396289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.756633ms","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-07-08T09:54:49.396474Z","caller":"traceutil/trace.go:171","msg":"trace[71792225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1266; }","duration":"716.942369ms","start":"2026-07-08T09:54:48.679517Z","end":"2026-07-08T09:54:49.396459Z","steps":["trace[71792225] 'agreement among raft nodes before linearized reading' (duration: 716.720593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:49.396542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:48.679512Z","time spent":"717.014431ms","remote":"127.0.0.1:45718","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-07-08T09:54:49.572501Z","caller":"traceutil/trace.go:171","msg":"trace[1414338068] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"171.797458ms","start":"2026-07-08T09:54:49.400625Z","end":"2026-07-08T09:54:49.572422Z","steps":["trace[1414338068] 'process raft request' (duration: 171.637154ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:49.608564Z","caller":"traceutil/trace.go:171","msg":"trace[1571132286] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"207.600742ms","start":"2026-07-08T09:54:49.400921Z","end":"2026-07-08T09:54:49.608522Z","steps":["trace[1571132286] 'process raft request' (duration: 207.383246ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:49.60856Z","caller":"traceutil/trace.go:171","msg":"trace[648391931] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"207.50414ms","start":"2026-07-08T09:54:49.401041Z","end":"2026-07-08T09:54:49.608545Z","steps":["trace[648391931] 'process raft request' (duration: 207.378216ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:49.609006Z","caller":"traceutil/trace.go:171","msg":"trace[84302215] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"206.734789ms","start":"2026-07-08T09:54:49.401757Z","end":"2026-07-08T09:54:49.608492Z","steps":["trace[84302215] 'process raft request' (duration: 206.698658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:50.64092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.588565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:50.640994Z","caller":"traceutil/trace.go:171","msg":"trace[641248649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"187.702868ms","start":"2026-07-08T09:54:50.453275Z","end":"2026-07-08T09:54:50.640978Z","steps":["trace[641248649] 'range keys from in-memory index tree' (duration: 187.521693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:51.143881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621894357,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T09:54:51.308013Z","caller":"traceutil/trace.go:171","msg":"trace[1197196779] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"664.61128ms","start":"2026-07-08T09:54:50.643374Z","end":"2026-07-08T09:54:51.307985Z","steps":["trace[1197196779] 'read index received' (duration: 664.337112ms)","trace[1197196779] 'applied index is now lower than readState.Index' (duration: 273.078µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:51.308088Z","caller":"traceutil/trace.go:171","msg":"trace[221873638] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"689.435934ms","start":"2026-07-08T09:54:50.618627Z","end":"2026-07-08T09:54:51.308063Z","steps":["trace[221873638] 'process raft request' (duration: 689.203388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:51.308185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.771303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T09:54:51.308261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:50.618604Z","time spent":"689.580887ms","remote":"127.0.0.1:45718","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-07-08T09:54:51.308271Z","caller":"traceutil/trace.go:171","msg":"trace[240271479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"664.917407ms","start":"2026-07-08T09:54:50.643327Z","end":"2026-07-08T09:54:51.308244Z","steps":["trace[240271479] 'agreement among raft nodes before linearized reading' (duration: 664.770383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:51.308338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:50.64328Z","time spent":"665.04466ms","remote":"127.0.0.1:45470","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-07-08T09:54:51.690445Z","caller":"traceutil/trace.go:171","msg":"trace[397287569] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"286.717498ms","start":"2026-07-08T09:54:51.403704Z","end":"2026-07-08T09:54:51.690421Z","steps":["trace[397287569] 'process raft request' (duration: 286.589304ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:51.690665Z","caller":"traceutil/trace.go:171","msg":"trace[1989513959] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1325; }","duration":"115.077713ms","start":"2026-07-08T09:54:51.575566Z","end":"2026-07-08T09:54:51.690644Z","steps":["trace[1989513959] 'read index received' (duration: 115.069433ms)","trace[1989513959] 'applied index is now lower than readState.Index' (duration: 6.68µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:54:51.691061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.482373ms","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-07-08T09:54:51.691124Z","caller":"traceutil/trace.go:171","msg":"trace[414090366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"115.589336ms","start":"2026-07-08T09:54:51.575518Z","end":"2026-07-08T09:54:51.691107Z","steps":["trace[414090366] 'agreement among raft nodes before linearized reading' (duration: 115.449263ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:54:53.498034Z","caller":"traceutil/trace.go:171","msg":"trace[2068746129] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1340; }","duration":"170.863034ms","start":"2026-07-08T09:54:53.327147Z","end":"2026-07-08T09:54:53.49801Z","steps":["trace[2068746129] 'read index received' (duration: 170.640728ms)","trace[2068746129] 'applied index is now lower than readState.Index' (duration: 221.366µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:53.498339Z","caller":"traceutil/trace.go:171","msg":"trace[1257782180] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"185.221613ms","start":"2026-07-08T09:54:53.313106Z","end":"2026-07-08T09:54:53.498327Z","steps":["trace[1257782180] 'process raft request' (duration: 184.75333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:53.498558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.395029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-qz9z4\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-07-08T09:54:53.498629Z","caller":"traceutil/trace.go:171","msg":"trace[1551094807] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-qz9z4; range_end:; response_count:1; response_revision:1290; }","duration":"171.506882ms","start":"2026-07-08T09:54:53.327106Z","end":"2026-07-08T09:54:53.498613Z","steps":["trace[1551094807] 'agreement among raft nodes before linearized reading' (duration: 171.341827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:58.689337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326770621894470,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T09:54:58.889294Z","caller":"traceutil/trace.go:171","msg":"trace[664510689] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1389; }","duration":"700.878105ms","start":"2026-07-08T09:54:58.188391Z","end":"2026-07-08T09:54:58.88927Z","steps":["trace[664510689] 'read index received' (duration: 700.68726ms)","trace[664510689] 'applied index is now lower than readState.Index' (duration: 189.525µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:54:58.889339Z","caller":"traceutil/trace.go:171","msg":"trace[1012964346] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"775.242026ms","start":"2026-07-08T09:54:58.114064Z","end":"2026-07-08T09:54:58.889306Z","steps":["trace[1012964346] 'process raft request' (duration: 775.075381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:58.889464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:58.114044Z","time spent":"775.362638ms","remote":"127.0.0.1:45542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:54:58.889475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.807625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:54:58.88951Z","caller":"traceutil/trace.go:171","msg":"trace[1068936323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1338; }","duration":"433.895868ms","start":"2026-07-08T09:54:58.455604Z","end":"2026-07-08T09:54:58.889499Z","steps":["trace[1068936323] 'agreement among raft nodes before linearized reading' (duration: 433.795175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:58.88949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.060739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T09:54:58.889544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:58.455585Z","time spent":"433.951239ms","remote":"127.0.0.1:45474","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-07-08T09:54:58.889553Z","caller":"traceutil/trace.go:171","msg":"trace[681938327] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1338; }","duration":"701.179073ms","start":"2026-07-08T09:54:58.188359Z","end":"2026-07-08T09:54:58.889538Z","steps":["trace[681938327] 'agreement among raft nodes before linearized reading' (duration: 701.022579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:54:58.889589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:58.188344Z","time spent":"701.239244ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-08T09:54:58.889828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.352585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T09:54:58.890115Z","caller":"traceutil/trace.go:171","msg":"trace[1266494007] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1338; }","duration":"295.431577ms","start":"2026-07-08T09:54:58.594427Z","end":"2026-07-08T09:54:58.889859Z","steps":["trace[1266494007] 'agreement among raft nodes before linearized reading' (duration: 295.360085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:00.667019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593845092s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:55:00.667151Z","caller":"traceutil/trace.go:171","msg":"trace[120971707] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"1.731284596s","start":"2026-07-08T09:54:58.93583Z","end":"2026-07-08T09:55:00.667114Z","steps":["trace[120971707] 'read index received' (duration: 137.265939ms)","trace[120971707] 'applied index is now lower than readState.Index' (duration: 1.594017057s)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:55:00.667247Z","caller":"traceutil/trace.go:171","msg":"trace[1373074538] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"1.731657347s","start":"2026-07-08T09:54:58.935575Z","end":"2026-07-08T09:55:00.667232Z","steps":["trace[1373074538] 'process raft request' (duration: 137.528936ms)","trace[1373074538] 'compare' (duration: 1.593208476s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:55:00.667315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:58.935556Z","time spent":"1.731729499s","remote":"127.0.0.1:45542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":911,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:55:00.667431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.731576044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-08T09:55:00.667498Z","caller":"traceutil/trace.go:171","msg":"trace[965309318] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1340; }","duration":"1.731674606s","start":"2026-07-08T09:54:58.935806Z","end":"2026-07-08T09:55:00.667481Z","steps":["trace[965309318] 'agreement among raft nodes before linearized reading' (duration: 1.7314304s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:00.66753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:58.935798Z","time spent":"1.731725438s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-08T09:55:00.71767Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.584430285s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T09:55:00.718013Z","caller":"traceutil/trace.go:171","msg":"trace[262745855] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"1.584842607s","start":"2026-07-08T09:54:59.133152Z","end":"2026-07-08T09:55:00.717995Z","steps":["trace[262745855] 'process raft request' (duration: 1.584745954s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:00.718117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:59.13313Z","time spent":"1.584935838s","remote":"127.0.0.1:45542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T09:55:01.053003Z","caller":"traceutil/trace.go:171","msg":"trace[950716067] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1392; }","duration":"385.753157ms","start":"2026-07-08T09:55:00.667202Z","end":"2026-07-08T09:55:01.052955Z","steps":["trace[950716067] 'read index received' (duration: 50.734187ms)","trace[950716067] 'applied index is now lower than readState.Index' (duration: 335.01708ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:55:01.053416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.86875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-08T09:55:01.053533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011629606s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T09:55:01.053592Z","caller":"traceutil/trace.go:171","msg":"trace[1696276385] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1344; }","duration":"1.011702677s","start":"2026-07-08T09:55:00.041868Z","end":"2026-07-08T09:55:01.053571Z","steps":["trace[1696276385] 'agreement among raft nodes before linearized reading' (duration: 1.011634326s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.053632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:55:00.04185Z","time spent":"1.011774241s","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-07-08T09:55:01.053706Z","caller":"traceutil/trace.go:171","msg":"trace[780917282] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"1.522245717s","start":"2026-07-08T09:54:59.531448Z","end":"2026-07-08T09:55:01.053693Z","steps":["trace[780917282] 'process raft request' (duration: 1.521278201s)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:55:01.053531Z","caller":"traceutil/trace.go:171","msg":"trace[2127222909] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1344; }","duration":"664.041464ms","start":"2026-07-08T09:55:00.389476Z","end":"2026-07-08T09:55:01.053518Z","steps":["trace[2127222909] 'agreement among raft nodes before linearized reading' (duration: 663.86505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.05401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23176943s","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-07-08T09:55:01.054046Z","caller":"traceutil/trace.go:171","msg":"trace[2108528003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1344; }","duration":"1.231852972s","start":"2026-07-08T09:54:59.822182Z","end":"2026-07-08T09:55:01.054035Z","steps":["trace[2108528003] 'agreement among raft nodes before linearized reading' (duration: 1.23178124s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.054074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:59.822149Z","time spent":"1.231917833s","remote":"127.0.0.1:45718","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-07-08T09:55:01.054178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332881385s","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-07-08T09:55:01.054231Z","caller":"traceutil/trace.go:171","msg":"trace[1070278275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1344; }","duration":"1.333018968s","start":"2026-07-08T09:54:59.721198Z","end":"2026-07-08T09:55:01.054217Z","steps":["trace[1070278275] 'agreement among raft nodes before linearized reading' (duration: 1.332876965s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.054268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:59.721153Z","time spent":"1.33310851s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T09:55:01.054415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271325792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T09:55:01.054447Z","caller":"traceutil/trace.go:171","msg":"trace[1224740883] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1344; }","duration":"1.271408464s","start":"2026-07-08T09:54:59.78303Z","end":"2026-07-08T09:55:01.054439Z","steps":["trace[1224740883] 'agreement among raft nodes before linearized reading' (duration: 1.271328802s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.054474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:59.783001Z","time spent":"1.271467496s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T09:55:01.054783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.717134ms","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-07-08T09:55:01.054824Z","caller":"traceutil/trace.go:171","msg":"trace[881353593] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1344; }","duration":"559.796036ms","start":"2026-07-08T09:55:00.495018Z","end":"2026-07-08T09:55:01.054814Z","steps":["trace[881353593] 'agreement among raft nodes before linearized reading' (duration: 559.699513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.054848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:55:00.494997Z","time spent":"559.845338ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-08T09:55:01.054932Z","caller":"traceutil/trace.go:171","msg":"trace[574219724] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"383.877919ms","start":"2026-07-08T09:55:00.671047Z","end":"2026-07-08T09:55:01.054924Z","steps":["trace[574219724] 'process raft request' (duration: 381.850875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.054946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.719347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T09:55:01.055015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:55:00.671025Z","time spent":"383.92906ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:55:01.055105Z","caller":"traceutil/trace.go:171","msg":"trace[1318550637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"605.811048ms","start":"2026-07-08T09:55:00.44918Z","end":"2026-07-08T09:55:01.054991Z","steps":["trace[1318550637] 'agreement among raft nodes before linearized reading' (duration: 605.728966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.055212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:55:00.449162Z","time spent":"605.991664ms","remote":"127.0.0.1:45474","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-07-08T09:55:01.053419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.795576741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-07-08T09:55:01.055375Z","caller":"traceutil/trace.go:171","msg":"trace[247362091] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1344; }","duration":"1.797629645s","start":"2026-07-08T09:54:59.257735Z","end":"2026-07-08T09:55:01.055365Z","steps":["trace[247362091] 'agreement among raft nodes before linearized reading' (duration: 1.795495549s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.055408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:59.257715Z","time spent":"1.797683877s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-08T09:55:01.053413Z","caller":"traceutil/trace.go:171","msg":"trace[546840810] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"1.34749085s","start":"2026-07-08T09:54:59.705891Z","end":"2026-07-08T09:55:01.053382Z","steps":["trace[546840810] 'process raft request' (duration: 1.346955926s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.055516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:59.705868Z","time spent":"1.349624045s","remote":"127.0.0.1:45718","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-07-08T09:55:01.053487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.634588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:55:01.055687Z","caller":"traceutil/trace.go:171","msg":"trace[790609728] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1344; }","duration":"930.887578ms","start":"2026-07-08T09:55:00.124791Z","end":"2026-07-08T09:55:01.055679Z","steps":["trace[790609728] 'agreement among raft nodes before linearized reading' (duration: 928.66722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.055723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:55:00.12477Z","time spent":"930.944559ms","remote":"127.0.0.1:44378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-08T09:55:01.053882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230149257s","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-07-08T09:55:01.055853Z","caller":"traceutil/trace.go:171","msg":"trace[174739326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"1.232151939s","start":"2026-07-08T09:54:59.823695Z","end":"2026-07-08T09:55:01.055847Z","steps":["trace[174739326] 'agreement among raft nodes before linearized reading' (duration: 1.230128276s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.055875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:59.823679Z","time spent":"1.23219026s","remote":"127.0.0.1:45718","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-07-08T09:55:01.053949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230541037s","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-07-08T09:55:01.056042Z","caller":"traceutil/trace.go:171","msg":"trace[1405850183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1344; }","duration":"1.232674822s","start":"2026-07-08T09:54:59.823359Z","end":"2026-07-08T09:55:01.056034Z","steps":["trace[1405850183] 'agreement among raft nodes before linearized reading' (duration: 1.230542567s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.056073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:59.823337Z","time spent":"1.232725604s","remote":"127.0.0.1:45718","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-07-08T09:55:01.05631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:55:00.389448Z","time spent":"664.566199ms","remote":"127.0.0.1:45816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-08T09:55:01.053819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:54:59.531421Z","time spent":"1.522324889s","remote":"127.0.0.1:45718","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-07-08T09:55:01.061336Z","caller":"traceutil/trace.go:171","msg":"trace[697077783] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"339.743665ms","start":"2026-07-08T09:55:00.721576Z","end":"2026-07-08T09:55:01.06132Z","steps":["trace[697077783] 'process raft request' (duration: 339.466788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.061424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:55:00.721475Z","time spent":"339.902759ms","remote":"127.0.0.1:45542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":861,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T09:55:01.061633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.899643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T09:55:01.061819Z","caller":"traceutil/trace.go:171","msg":"trace[1958482157] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1345; }","duration":"358.154689ms","start":"2026-07-08T09:55:00.703645Z","end":"2026-07-08T09:55:01.061799Z","steps":["trace[1958482157] 'agreement among raft nodes before linearized reading' (duration: 357.738708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:55:01.061919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:55:00.70363Z","time spent":"358.270583ms","remote":"127.0.0.1:45792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-07-08T09:55:25.35297Z","caller":"traceutil/trace.go:171","msg":"trace[547818209] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"135.303757ms","start":"2026-07-08T09:55:25.217641Z","end":"2026-07-08T09:55:25.352944Z","steps":["trace[547818209] 'process raft request' (duration: 135.211435ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:55:25.352985Z","caller":"traceutil/trace.go:171","msg":"trace[450849238] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"135.341458ms","start":"2026-07-08T09:55:25.21761Z","end":"2026-07-08T09:55:25.352951Z","steps":["trace[450849238] 'process raft request' (duration: 104.006481ms)","trace[450849238] 'compare' (duration: 30.905915ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:55:25.353275Z","caller":"traceutil/trace.go:171","msg":"trace[1170855368] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"135.045061ms","start":"2026-07-08T09:55:25.218207Z","end":"2026-07-08T09:55:25.353252Z","steps":["trace[1170855368] 'process raft request' (duration: 134.690192ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:55:25.35343Z","caller":"traceutil/trace.go:171","msg":"trace[1865211360] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"134.832474ms","start":"2026-07-08T09:55:25.218579Z","end":"2026-07-08T09:55:25.353412Z","steps":["trace[1865211360] 'process raft request' (duration: 134.573428ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:56:51.470005Z","caller":"traceutil/trace.go:171","msg":"trace[2133803480] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"196.995224ms","start":"2026-07-08T09:56:51.272959Z","end":"2026-07-08T09:56:51.469954Z","steps":["trace[2133803480] 'process raft request' (duration: 196.675666ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:56:51.521433Z","caller":"traceutil/trace.go:171","msg":"trace[105661287] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1934; }","duration":"243.460659ms","start":"2026-07-08T09:56:51.277948Z","end":"2026-07-08T09:56:51.521409Z","steps":["trace[105661287] 'read index received' (duration: 191.869179ms)","trace[105661287] 'applied index is now lower than readState.Index' (duration: 51.59052ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:56:51.521477Z","caller":"traceutil/trace.go:171","msg":"trace[1422917835] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"246.705635ms","start":"2026-07-08T09:56:51.274742Z","end":"2026-07-08T09:56:51.521448Z","steps":["trace[1422917835] 'process raft request' (duration: 246.48914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:56:51.521693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.719506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-08T09:56:51.521729Z","caller":"traceutil/trace.go:171","msg":"trace[2053814453] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1863; }","duration":"243.785328ms","start":"2026-07-08T09:56:51.277932Z","end":"2026-07-08T09:56:51.521717Z","steps":["trace[2053814453] 'agreement among raft nodes before linearized reading' (duration: 243.618274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:56:52.082516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.374163ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-08T09:56:52.082787Z","caller":"traceutil/trace.go:171","msg":"trace[1451409436] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"260.062488ms","start":"2026-07-08T09:56:51.822714Z","end":"2026-07-08T09:56:52.082777Z","steps":["trace[1451409436] 'read index received' (duration: 45.597953ms)","trace[1451409436] 'applied index is now lower than readState.Index' (duration: 214.463425ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:56:52.082904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.370238ms","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-07-08T09:56:52.082924Z","caller":"traceutil/trace.go:171","msg":"trace[1889871958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1865; }","duration":"345.429009ms","start":"2026-07-08T09:56:51.737488Z","end":"2026-07-08T09:56:52.082917Z","steps":["trace[1889871958] 'agreement among raft nodes before linearized reading' (duration: 345.330276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:56:52.082941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:56:51.737471Z","time spent":"345.46572ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T09:56:52.083267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.308044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T09:56:52.083355Z","caller":"traceutil/trace.go:171","msg":"trace[1218572313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1865; }","duration":"340.416297ms","start":"2026-07-08T09:56:51.742898Z","end":"2026-07-08T09:56:52.083315Z","steps":["trace[1218572313] 'agreement among raft nodes before linearized reading' (duration: 339.882433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:56:52.083392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:56:51.742875Z","time spent":"340.509779ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T09:56:52.083511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.197007ms","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-07-08T09:56:52.083691Z","caller":"traceutil/trace.go:171","msg":"trace[1110448844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1865; }","duration":"341.425173ms","start":"2026-07-08T09:56:51.742256Z","end":"2026-07-08T09:56:52.083681Z","steps":["trace[1110448844] 'agreement among raft nodes before linearized reading' (duration: 340.55312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:56:52.083768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:56:51.742235Z","time spent":"341.522286ms","remote":"127.0.0.1:45718","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-07-08T09:56:52.108626Z","caller":"traceutil/trace.go:171","msg":"trace[1624328681] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"281.858332ms","start":"2026-07-08T09:56:51.826748Z","end":"2026-07-08T09:56:52.108606Z","steps":["trace[1624328681] 'process raft request' (duration: 281.75561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:56:52.108723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.081924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-08T09:56:52.108772Z","caller":"traceutil/trace.go:171","msg":"trace[1740032218] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1866; }","duration":"264.179886ms","start":"2026-07-08T09:56:51.844582Z","end":"2026-07-08T09:56:52.108762Z","steps":["trace[1740032218] 'agreement among raft nodes before linearized reading' (duration: 264.012572ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:56:52.290047Z","caller":"traceutil/trace.go:171","msg":"trace[229771368] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"178.841636ms","start":"2026-07-08T09:56:52.111179Z","end":"2026-07-08T09:56:52.29002Z","steps":["trace[229771368] 'process raft request' (duration: 97.46875ms)","trace[229771368] 'compare' (duration: 81.199522ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:56:52.290451Z","caller":"traceutil/trace.go:171","msg":"trace[1968166215] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"177.310356ms","start":"2026-07-08T09:56:52.113107Z","end":"2026-07-08T09:56:52.290417Z","steps":["trace[1968166215] 'process raft request' (duration: 176.870734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:56:54.469929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.792509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:56:54.470017Z","caller":"traceutil/trace.go:171","msg":"trace[1959613099] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"279.764229ms","start":"2026-07-08T09:56:54.190238Z","end":"2026-07-08T09:56:54.470002Z","steps":["trace[1959613099] 'process raft request' (duration: 148.833107ms)","trace[1959613099] 'compare' (duration: 130.578002ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:56:54.502775Z","caller":"traceutil/trace.go:171","msg":"trace[1695806441] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1950; }","duration":"175.146969ms","start":"2026-07-08T09:56:54.327606Z","end":"2026-07-08T09:56:54.502753Z","steps":["trace[1695806441] 'read index received' (duration: 11.567105ms)","trace[1695806441] 'applied index is now lower than readState.Index' (duration: 163.578934ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:56:54.502827Z","caller":"traceutil/trace.go:171","msg":"trace[983914661] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"312.232894ms","start":"2026-07-08T09:56:54.190552Z","end":"2026-07-08T09:56:54.502785Z","steps":["trace[983914661] 'process raft request' (duration: 312.037678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:56:54.503045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.403645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-07-08T09:56:54.503057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:56:54.190545Z","time spent":"312.367128ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:56:54.503081Z","caller":"traceutil/trace.go:171","msg":"trace[760071823] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1878; }","duration":"175.496687ms","start":"2026-07-08T09:56:54.327575Z","end":"2026-07-08T09:56:54.503071Z","steps":["trace[760071823] 'agreement among raft nodes before linearized reading' (duration: 175.265851ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:56:54.503135Z","caller":"traceutil/trace.go:171","msg":"trace[1718135504] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"312.026338ms","start":"2026-07-08T09:56:54.191099Z","end":"2026-07-08T09:56:54.503125Z","steps":["trace[1718135504] 'process raft request' (duration: 311.624088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:56:54.503526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:56:54.191076Z","time spent":"312.407699ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T09:57:02.431283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.01519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:57:02.431391Z","caller":"traceutil/trace.go:171","msg":"trace[2143798697] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1989; }","duration":"128.462738ms","start":"2026-07-08T09:57:02.302909Z","end":"2026-07-08T09:57:02.431372Z","steps":["trace[2143798697] 'read index received' (duration: 43.961µs)","trace[2143798697] 'applied index is now lower than readState.Index' (duration: 128.417107ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:57:02.431435Z","caller":"traceutil/trace.go:171","msg":"trace[620721797] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"211.350643ms","start":"2026-07-08T09:57:02.22005Z","end":"2026-07-08T09:57:02.4314Z","steps":["trace[620721797] 'process raft request' (duration: 65.112787ms)","trace[620721797] 'compare' (duration: 145.679552ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:57:02.431593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.677403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-08T09:57:02.431732Z","caller":"traceutil/trace.go:171","msg":"trace[824499547] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1913; }","duration":"128.829427ms","start":"2026-07-08T09:57:02.302876Z","end":"2026-07-08T09:57:02.431705Z","steps":["trace[824499547] 'agreement among raft nodes before linearized reading' (duration: 128.54378ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:57:02.623787Z","caller":"traceutil/trace.go:171","msg":"trace[550815459] linearizableReadLoop","detail":"{readStateIndex:1991; appliedIndex:1990; }","duration":"171.967305ms","start":"2026-07-08T09:57:02.451764Z","end":"2026-07-08T09:57:02.623731Z","steps":["trace[550815459] 'read index received' (duration: 111.318735ms)","trace[550815459] 'applied index is now lower than readState.Index' (duration: 60.64709ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:57:02.623974Z","caller":"traceutil/trace.go:171","msg":"trace[925127475] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"187.415363ms","start":"2026-07-08T09:57:02.436536Z","end":"2026-07-08T09:57:02.623951Z","steps":["trace[925127475] 'process raft request' (duration: 126.600378ms)","trace[925127475] 'compare' (duration: 60.365713ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:57:02.624245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.894862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-08T09:57:02.624542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.605807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T09:57:02.624636Z","caller":"traceutil/trace.go:171","msg":"trace[351829026] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1914; }","duration":"121.764271ms","start":"2026-07-08T09:57:02.502851Z","end":"2026-07-08T09:57:02.624615Z","steps":["trace[351829026] 'agreement among raft nodes before linearized reading' (duration: 121.483454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:57:02.624712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.683543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T09:57:02.625008Z","caller":"traceutil/trace.go:171","msg":"trace[467993503] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1914; }","duration":"172.130249ms","start":"2026-07-08T09:57:02.452225Z","end":"2026-07-08T09:57:02.624355Z","steps":["trace[467993503] 'agreement among raft nodes before linearized reading' (duration: 171.79299ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:57:02.625138Z","caller":"traceutil/trace.go:171","msg":"trace[1511515040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1914; }","duration":"173.3435ms","start":"2026-07-08T09:57:02.451745Z","end":"2026-07-08T09:57:02.625088Z","steps":["trace[1511515040] 'agreement among raft nodes before linearized reading' (duration: 172.497288ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:57:02.738371Z","caller":"traceutil/trace.go:171","msg":"trace[589368775] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"106.364705ms","start":"2026-07-08T09:57:02.631983Z","end":"2026-07-08T09:57:02.738348Z","steps":["trace[589368775] 'process raft request' (duration: 106.330964ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:57:02.73864Z","caller":"traceutil/trace.go:171","msg":"trace[1620895087] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"107.716091ms","start":"2026-07-08T09:57:02.630914Z","end":"2026-07-08T09:57:02.73863Z","steps":["trace[1620895087] 'process raft request' (duration: 98.613551ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:57:02.73878Z","caller":"traceutil/trace.go:171","msg":"trace[554599528] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"106.799707ms","start":"2026-07-08T09:57:02.631952Z","end":"2026-07-08T09:57:02.738752Z","steps":["trace[554599528] 'process raft request' (duration: 106.318664ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:57:03.955102Z","caller":"traceutil/trace.go:171","msg":"trace[131882485] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"122.511041ms","start":"2026-07-08T09:57:03.832561Z","end":"2026-07-08T09:57:03.955072Z","steps":["trace[131882485] 'process raft request' (duration: 122.266645ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:57:04.107916Z","caller":"traceutil/trace.go:171","msg":"trace[1312143536] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"114.259763ms","start":"2026-07-08T09:57:03.993638Z","end":"2026-07-08T09:57:04.107898Z","steps":["trace[1312143536] 'process raft request' (duration: 48.867888ms)","trace[1312143536] 'compare' (duration: 65.320963ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:57:05.15822Z","caller":"traceutil/trace.go:171","msg":"trace[266643367] linearizableReadLoop","detail":"{readStateIndex:2000; appliedIndex:1999; }","duration":"416.296628ms","start":"2026-07-08T09:57:04.741899Z","end":"2026-07-08T09:57:05.158196Z","steps":["trace[266643367] 'read index received' (duration: 415.892157ms)","trace[266643367] 'applied index is now lower than readState.Index' (duration: 403.581µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:57:05.158274Z","caller":"traceutil/trace.go:171","msg":"trace[2090377563] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"431.181911ms","start":"2026-07-08T09:57:04.72705Z","end":"2026-07-08T09:57:05.158232Z","steps":["trace[2090377563] 'process raft request' (duration: 430.787391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:57:05.158407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.499944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-07-08T09:57:05.158414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:57:04.727027Z","time spent":"431.311774ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:57:05.15844Z","caller":"traceutil/trace.go:171","msg":"trace[1873958300] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1923; }","duration":"416.566105ms","start":"2026-07-08T09:57:04.741865Z","end":"2026-07-08T09:57:05.158431Z","steps":["trace[1873958300] 'agreement among raft nodes before linearized reading' (duration: 416.444582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:57:05.158457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:57:04.741852Z","time spent":"416.600567ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-08T09:57:05.158584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.387831ms","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-07-08T09:57:05.15866Z","caller":"traceutil/trace.go:171","msg":"trace[275064090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1923; }","duration":"416.494153ms","start":"2026-07-08T09:57:04.742148Z","end":"2026-07-08T09:57:05.158642Z","steps":["trace[275064090] 'agreement among raft nodes before linearized reading' (duration: 416.252077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:57:05.158712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:57:04.742138Z","time spent":"416.564525ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T09:57:05.158794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.454552ms","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-07-08T09:57:05.158842Z","caller":"traceutil/trace.go:171","msg":"trace[1979955484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1923; }","duration":"416.74819ms","start":"2026-07-08T09:57:04.74208Z","end":"2026-07-08T09:57:05.158829Z","steps":["trace[1979955484] 'agreement among raft nodes before linearized reading' (duration: 416.250937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:57:05.159094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:57:04.742061Z","time spent":"416.936615ms","remote":"127.0.0.1:45718","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-07-08T09:57:05.367533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.427343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T09:57:05.367713Z","caller":"traceutil/trace.go:171","msg":"trace[79497687] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"203.069384ms","start":"2026-07-08T09:57:05.164614Z","end":"2026-07-08T09:57:05.367683Z","steps":["trace[79497687] 'process raft request' (duration: 62.416586ms)","trace[79497687] 'compare' (duration: 140.260418ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:57:05.368098Z","caller":"traceutil/trace.go:171","msg":"trace[1149157259] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"203.448796ms","start":"2026-07-08T09:57:05.164629Z","end":"2026-07-08T09:57:05.368078Z","steps":["trace[1149157259] 'process raft request' (duration: 203.001023ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:57:05.36874Z","caller":"traceutil/trace.go:171","msg":"trace[508490695] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"204.095652ms","start":"2026-07-08T09:57:05.164625Z","end":"2026-07-08T09:57:05.36872Z","steps":["trace[508490695] 'process raft request' (duration: 203.402504ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:57:12.250231Z","caller":"traceutil/trace.go:171","msg":"trace[1281500918] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"106.860068ms","start":"2026-07-08T09:57:12.143337Z","end":"2026-07-08T09:57:12.250197Z","steps":["trace[1281500918] 'process raft request' (duration: 106.630992ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:58:07.953721Z","caller":"traceutil/trace.go:171","msg":"trace[106317733] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"176.97938ms","start":"2026-07-08T09:58:07.776717Z","end":"2026-07-08T09:58:07.953696Z","steps":["trace[106317733] 'process raft request' (duration: 106.55071ms)","trace[106317733] 'compare' (duration: 70.234425ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:58:08.209469Z","caller":"traceutil/trace.go:171","msg":"trace[1379539539] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"202.84048ms","start":"2026-07-08T09:58:08.006607Z","end":"2026-07-08T09:58:08.209448Z","steps":["trace[1379539539] 'process raft request' (duration: 143.84183ms)","trace[1379539539] 'compare' (duration: 58.883557ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T09:58:08.210001Z","caller":"traceutil/trace.go:171","msg":"trace[949065672] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"200.633251ms","start":"2026-07-08T09:58:08.009357Z","end":"2026-07-08T09:58:08.209991Z","steps":["trace[949065672] 'process raft request' (duration: 200.58008ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T09:59:08.673572Z","caller":"traceutil/trace.go:171","msg":"trace[1082718349] transaction","detail":"{read_only:false; response_revision:2515; number_of_response:1; }","duration":"428.192651ms","start":"2026-07-08T09:59:08.245346Z","end":"2026-07-08T09:59:08.673539Z","steps":["trace[1082718349] 'process raft request' (duration: 427.958984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:59:08.673927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:59:08.245331Z","time spent":"428.357435ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T09:59:08.674155Z","caller":"traceutil/trace.go:171","msg":"trace[896595331] linearizableReadLoop","detail":"{readStateIndex:2618; appliedIndex:2618; }","duration":"389.662596ms","start":"2026-07-08T09:59:08.284459Z","end":"2026-07-08T09:59:08.674121Z","steps":["trace[896595331] 'read index received' (duration: 389.644216ms)","trace[896595331] 'applied index is now lower than readState.Index' (duration: 15.51µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T09:59:08.67449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.006735ms","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-07-08T09:59:08.674536Z","caller":"traceutil/trace.go:171","msg":"trace[1059708596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2515; }","duration":"390.107689ms","start":"2026-07-08T09:59:08.284404Z","end":"2026-07-08T09:59:08.674512Z","steps":["trace[1059708596] 'agreement among raft nodes before linearized reading' (duration: 389.863552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:59:08.674579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:59:08.284378Z","time spent":"390.1898ms","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T09:59:08.743252Z","caller":"traceutil/trace.go:171","msg":"trace[92877834] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"326.179135ms","start":"2026-07-08T09:59:08.417052Z","end":"2026-07-08T09:59:08.743232Z","steps":["trace[92877834] 'process raft request' (duration: 326.060802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T09:59:08.743405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T09:59:08.417028Z","time spent":"326.289248ms","remote":"127.0.0.1:45718","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-07-08T09:59:08.743802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.009457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-08T09:59:08.744232Z","caller":"traceutil/trace.go:171","msg":"trace[1908770679] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2516; }","duration":"251.139211ms","start":"2026-07-08T09:59:08.492732Z","end":"2026-07-08T09:59:08.743871Z","steps":["trace[1908770679] 'agreement among raft nodes before linearized reading' (duration: 250.7474ms)"],"step_count":1}