{"level":"warn","ts":"2026-08-11T11:12:41.133211Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T11:12:41.133304Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.83: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.83:2380","--initial-cluster=instance=https://199.19.213.83:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.83:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.83: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-08-11T11:12:41.133368Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T11:12:41.133377Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.83:2380"]} {"level":"info","ts":"2026-08-11T11:12:41.133395Z","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-08-11T11:12:41.134302Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"]} {"level":"info","ts":"2026-08-11T11:12:41.134396Z","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.83:2380"],"listen-peer-urls":["https://199.19.213.83:2380"],"advertise-client-urls":["https://199.19.213.83:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.83: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-08-11T11:12:41.137508Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.852831ms"} {"level":"info","ts":"2026-08-11T11:12:41.143559Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5887d25060a34f1b","cluster-id":"8c4d9ec591b76d84"} {"level":"info","ts":"2026-08-11T11:12:41.143648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T11:12:41.14367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became follower at term 0"} {"level":"info","ts":"2026-08-11T11:12:41.143681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5887d25060a34f1b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T11:12:41.143696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became follower at term 1"} {"level":"info","ts":"2026-08-11T11:12:41.143737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=(6379298639854128923)"} {"level":"warn","ts":"2026-08-11T11:12:41.149114Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T11:12:41.150932Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T11:12:41.152456Z","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-08-11T11:12:41.157349Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5887d25060a34f1b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T11:12:41.164332Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5887d25060a34f1b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T11:12:41.164557Z","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-08-11T11:12:41.164683Z","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-08-11T11:12:41.164711Z","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-08-11T11:12:41.165162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=(6379298639854128923)"} {"level":"info","ts":"2026-08-11T11:12:41.16589Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8c4d9ec591b76d84","local-member-id":"5887d25060a34f1b","added-peer-id":"5887d25060a34f1b","added-peer-peer-urls":["https://199.19.213.83:2380"]} {"level":"info","ts":"2026-08-11T11:12:41.16794Z","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-08-11T11:12:41.16817Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5887d25060a34f1b","initial-advertise-peer-urls":["https://199.19.213.83:2380"],"listen-peer-urls":["https://199.19.213.83:2380"],"advertise-client-urls":["https://199.19.213.83:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T11:12:41.168205Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T11:12:41.168301Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.83:2380"} {"level":"info","ts":"2026-08-11T11:12:41.168313Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.83:2380"} {"level":"info","ts":"2026-08-11T11:12:42.044681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T11:12:42.044734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T11:12:42.044758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b received MsgPreVoteResp from 5887d25060a34f1b at term 1"} {"level":"info","ts":"2026-08-11T11:12:42.044775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became candidate at term 2"} {"level":"info","ts":"2026-08-11T11:12:42.044783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b received MsgVoteResp from 5887d25060a34f1b at term 2"} {"level":"info","ts":"2026-08-11T11:12:42.044794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became leader at term 2"} {"level":"info","ts":"2026-08-11T11:12:42.044804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5887d25060a34f1b elected leader 5887d25060a34f1b at term 2"} {"level":"info","ts":"2026-08-11T11:12:42.046144Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:12:42.047081Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5887d25060a34f1b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.83:2379]}","request-path":"/0/members/5887d25060a34f1b/attributes","cluster-id":"8c4d9ec591b76d84","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T11:12:42.047097Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T11:12:42.047183Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T11:12:42.047459Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T11:12:42.047494Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T11:12:42.047594Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8c4d9ec591b76d84","local-member-id":"5887d25060a34f1b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:12:42.047689Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:12:42.047726Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:12:42.050605Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.83:2379"} {"level":"info","ts":"2026-08-11T11:12:42.051341Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T11:12:43.616963Z","caller":"traceutil/trace.go:171","msg":"trace[321226060] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"315.380654ms","start":"2026-08-11T11:12:43.301559Z","end":"2026-08-11T11:12:43.616939Z","steps":["trace[321226060] 'process raft request' (duration: 315.121348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:43.617944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:12:43.301547Z","time spent":"315.504607ms","remote":"127.0.0.1:44028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:12:43.688819Z","caller":"traceutil/trace.go:171","msg":"trace[1126983697] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"374.992454ms","start":"2026-08-11T11:12:43.31379Z","end":"2026-08-11T11:12:43.688783Z","steps":["trace[1126983697] 'read index received' (duration: 302.83019ms)","trace[1126983697] 'applied index is now lower than readState.Index' (duration: 72.161314ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:12:43.689102Z","caller":"traceutil/trace.go:171","msg":"trace[349574115] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"295.23953ms","start":"2026-08-11T11:12:43.393718Z","end":"2026-08-11T11:12:43.688957Z","steps":["trace[349574115] 'process raft request' (duration: 294.491242ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:12:43.689577Z","caller":"traceutil/trace.go:171","msg":"trace[1544166391] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"300.804249ms","start":"2026-08-11T11:12:43.388632Z","end":"2026-08-11T11:12:43.689436Z","steps":["trace[1544166391] 'process raft request' (duration: 299.551598ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:12:43.68967Z","caller":"traceutil/trace.go:171","msg":"trace[1706967118] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"294.105452ms","start":"2026-08-11T11:12:43.395525Z","end":"2026-08-11T11:12:43.689631Z","steps":["trace[1706967118] 'process raft request' (duration: 293.124038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:43.689684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:12:43.388602Z","time spent":"301.030015ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:12:43.690237Z","caller":"traceutil/trace.go:171","msg":"trace[1986912945] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"306.113242ms","start":"2026-08-11T11:12:43.382156Z","end":"2026-08-11T11:12:43.688269Z","steps":["trace[1986912945] 'process raft request' (duration: 305.980989ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:43.690404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:12:43.38213Z","time spent":"308.240536ms","remote":"127.0.0.1:44404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:12:43.690333Z","caller":"traceutil/trace.go:171","msg":"trace[871905660] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"294.79637ms","start":"2026-08-11T11:12:43.395251Z","end":"2026-08-11T11:12:43.690047Z","steps":["trace[871905660] 'process raft request' (duration: 293.001025ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:12:43.690599Z","caller":"traceutil/trace.go:171","msg":"trace[1576768084] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"295.159968ms","start":"2026-08-11T11:12:43.395424Z","end":"2026-08-11T11:12:43.690584Z","steps":["trace[1576768084] 'process raft request' (duration: 292.84698ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:12:43.690653Z","caller":"traceutil/trace.go:171","msg":"trace[1278867956] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"383.039155ms","start":"2026-08-11T11:12:43.305264Z","end":"2026-08-11T11:12:43.688303Z","steps":["trace[1278867956] 'process raft request' (duration: 382.695866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:43.69077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:12:43.305258Z","time spent":"385.475616ms","remote":"127.0.0.1:44032","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-08-11T11:12:43.691767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.965298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-11T11:12:43.691839Z","caller":"traceutil/trace.go:171","msg":"trace[444658918] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"378.04158ms","start":"2026-08-11T11:12:43.313778Z","end":"2026-08-11T11:12:43.69182Z","steps":["trace[444658918] 'agreement among raft nodes before linearized reading' (duration: 377.64866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:43.691867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:12:43.313773Z","time spent":"378.086011ms","remote":"127.0.0.1:44342","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-08-11T11:12:43.692385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.102597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-11T11:12:43.69244Z","caller":"traceutil/trace.go:171","msg":"trace[699480506] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"341.24574ms","start":"2026-08-11T11:12:43.351175Z","end":"2026-08-11T11:12:43.692421Z","steps":["trace[699480506] 'agreement among raft nodes before linearized reading' (duration: 340.278806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:43.692486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:12:43.351153Z","time spent":"341.324383ms","remote":"127.0.0.1:44094","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":"info","ts":"2026-08-11T11:12:43.692561Z","caller":"traceutil/trace.go:171","msg":"trace[669131070] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"296.769279ms","start":"2026-08-11T11:12:43.395596Z","end":"2026-08-11T11:12:43.692365Z","steps":["trace[669131070] 'process raft request' (duration: 293.109757ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:12:43.693184Z","caller":"traceutil/trace.go:171","msg":"trace[149215855] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"297.121778ms","start":"2026-08-11T11:12:43.395719Z","end":"2026-08-11T11:12:43.692841Z","steps":["trace[149215855] 'process raft request' (duration: 293.017965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:43.693636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.412095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.83\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-11T11:12:43.693682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.518247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-11T11:12:43.693677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.353384ms","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-08-11T11:12:43.693738Z","caller":"traceutil/trace.go:171","msg":"trace[1236252780] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"255.595369ms","start":"2026-08-11T11:12:43.438116Z","end":"2026-08-11T11:12:43.693712Z","steps":["trace[1236252780] 'agreement among raft nodes before linearized reading' (duration: 255.493457ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:12:43.693728Z","caller":"traceutil/trace.go:171","msg":"trace[695635774] range","detail":"{range_begin:/registry/masterleases/199.19.213.83; range_end:; response_count:0; response_revision:11; }","duration":"313.523978ms","start":"2026-08-11T11:12:43.380165Z","end":"2026-08-11T11:12:43.693689Z","steps":["trace[695635774] 'agreement among raft nodes before linearized reading' (duration: 313.419935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:43.693815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:12:43.380149Z","time spent":"313.654851ms","remote":"127.0.0.1:44004","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.83\" "} {"level":"warn","ts":"2026-08-11T11:12:43.693877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.213429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-11T11:12:43.693859Z","caller":"traceutil/trace.go:171","msg":"trace[1397912791] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"311.509177ms","start":"2026-08-11T11:12:43.382257Z","end":"2026-08-11T11:12:43.693766Z","steps":["trace[1397912791] 'agreement among raft nodes before linearized reading' (duration: 311.306652ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:12:43.69392Z","caller":"traceutil/trace.go:171","msg":"trace[909496185] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"311.24314ms","start":"2026-08-11T11:12:43.382655Z","end":"2026-08-11T11:12:43.693898Z","steps":["trace[909496185] 'agreement among raft nodes before linearized reading' (duration: 311.194349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:12:43.693944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:12:43.382248Z","time spent":"311.668112ms","remote":"127.0.0.1:44052","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-08-11T11:12:43.69395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:12:43.38265Z","time spent":"311.291212ms","remote":"127.0.0.1:44094","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":"info","ts":"2026-08-11T11:13:02.349191Z","caller":"traceutil/trace.go:171","msg":"trace[1445389281] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"355.668371ms","start":"2026-08-11T11:13:01.993499Z","end":"2026-08-11T11:13:02.349168Z","steps":["trace[1445389281] 'read index received' (duration: 355.479516ms)","trace[1445389281] 'applied index is now lower than readState.Index' (duration: 188.055µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:13:02.349437Z","caller":"traceutil/trace.go:171","msg":"trace[2142395145] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"415.088756ms","start":"2026-08-11T11:13:01.934293Z","end":"2026-08-11T11:13:02.349381Z","steps":["trace[2142395145] 'process raft request' (duration: 414.740618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:02.349542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.010499ms","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-08-11T11:13:02.349602Z","caller":"traceutil/trace.go:171","msg":"trace[963454579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"356.121503ms","start":"2026-08-11T11:13:01.993467Z","end":"2026-08-11T11:13:02.349589Z","steps":["trace[963454579] 'agreement among raft nodes before linearized reading' (duration: 355.915057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:02.349641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:01.993455Z","time spent":"356.177314ms","remote":"127.0.0.1:44224","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-08-11T11:13:02.349646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:01.934276Z","time spent":"415.270342ms","remote":"127.0.0.1:44224","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-08-11T11:13:02.349787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.452856ms","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-08-11T11:13:02.34984Z","caller":"traceutil/trace.go:171","msg":"trace[61446730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"293.527218ms","start":"2026-08-11T11:13:02.056294Z","end":"2026-08-11T11:13:02.349821Z","steps":["trace[61446730] 'agreement among raft nodes before linearized reading' (duration: 293.467557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:02.349891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.59794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:13:02.349948Z","caller":"traceutil/trace.go:171","msg":"trace[689806746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:424; }","duration":"225.695232ms","start":"2026-08-11T11:13:02.124241Z","end":"2026-08-11T11:13:02.349936Z","steps":["trace[689806746] 'agreement among raft nodes before linearized reading' (duration: 225.5938ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:02.807544Z","caller":"traceutil/trace.go:171","msg":"trace[791975174] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"454.704097ms","start":"2026-08-11T11:13:02.352821Z","end":"2026-08-11T11:13:02.807525Z","steps":["trace[791975174] 'process raft request' (duration: 454.603075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:02.807693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:02.352799Z","time spent":"454.806429ms","remote":"127.0.0.1:44224","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-08-11T11:13:03.055202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.534964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:13:03.055299Z","caller":"traceutil/trace.go:171","msg":"trace[1126885189] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"321.977939ms","start":"2026-08-11T11:13:02.733305Z","end":"2026-08-11T11:13:03.055283Z","steps":["trace[1126885189] 'read index received' (duration: 74.355988ms)","trace[1126885189] 'applied index is now lower than readState.Index' (duration: 247.620571ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:03.055426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.107612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:13:03.055459Z","caller":"traceutil/trace.go:171","msg":"trace[523026214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"322.192074ms","start":"2026-08-11T11:13:02.733258Z","end":"2026-08-11T11:13:03.05545Z","steps":["trace[523026214] 'agreement among raft nodes before linearized reading' (duration: 322.068671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:03.055496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:02.733238Z","time spent":"322.249886ms","remote":"127.0.0.1:43960","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-08-11T11:13:03.055863Z","caller":"traceutil/trace.go:171","msg":"trace[2069547956] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"701.872146ms","start":"2026-08-11T11:13:02.35397Z","end":"2026-08-11T11:13:03.055843Z","steps":["trace[2069547956] 'process raft request' (duration: 512.469971ms)","trace[2069547956] 'compare' (duration: 188.43095ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:03.055955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:02.353963Z","time spent":"701.954437ms","remote":"127.0.0.1:44224","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-08-11T11:13:03.999562Z","caller":"traceutil/trace.go:171","msg":"trace[194823418] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"547.918797ms","start":"2026-08-11T11:13:03.451621Z","end":"2026-08-11T11:13:03.999539Z","steps":["trace[194823418] 'process raft request' (duration: 547.717832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:03.999753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:03.451605Z","time spent":"548.026629ms","remote":"127.0.0.1:44224","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-08-11T11:13:03.999856Z","caller":"traceutil/trace.go:171","msg":"trace[1313889779] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:440; }","duration":"268.559023ms","start":"2026-08-11T11:13:03.731261Z","end":"2026-08-11T11:13:03.99982Z","steps":["trace[1313889779] 'read index received' (duration: 268.550283ms)","trace[1313889779] 'applied index is now lower than readState.Index' (duration: 6.95µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:03.99997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.715157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:13:04Z","caller":"traceutil/trace.go:171","msg":"trace[436805195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"268.796069ms","start":"2026-08-11T11:13:03.731195Z","end":"2026-08-11T11:13:03.999991Z","steps":["trace[436805195] 'agreement among raft nodes before linearized reading' (duration: 268.723978ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:21.238758Z","caller":"traceutil/trace.go:171","msg":"trace[2084474720] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"532.581744ms","start":"2026-08-11T11:13:20.706152Z","end":"2026-08-11T11:13:21.238734Z","steps":["trace[2084474720] 'read index received' (duration: 483.20927ms)","trace[2084474720] 'applied index is now lower than readState.Index' (duration: 49.371324ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:21.238919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.748887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:13:21.238976Z","caller":"traceutil/trace.go:171","msg":"trace[1699945175] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:532; }","duration":"532.8593ms","start":"2026-08-11T11:13:20.706101Z","end":"2026-08-11T11:13:21.23896Z","steps":["trace[1699945175] 'agreement among raft nodes before linearized reading' (duration: 532.749238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:21.239024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:20.706082Z","time spent":"532.929473ms","remote":"127.0.0.1:44062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-08-11T11:13:21.239257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.361239ms","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-08-11T11:13:21.239315Z","caller":"traceutil/trace.go:171","msg":"trace[2115087917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"236.451481ms","start":"2026-08-11T11:13:21.002852Z","end":"2026-08-11T11:13:21.239304Z","steps":["trace[2115087917] 'agreement among raft nodes before linearized reading' (duration: 236.062031ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:24.470643Z","caller":"traceutil/trace.go:171","msg":"trace[936883742] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"347.173429ms","start":"2026-08-11T11:13:24.123444Z","end":"2026-08-11T11:13:24.470618Z","steps":["trace[936883742] 'process raft request' (duration: 346.934613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:24.471194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:24.123418Z","time spent":"347.502828ms","remote":"127.0.0.1:44224","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-08-11T11:13:28.985852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.901362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:13:28.985952Z","caller":"traceutil/trace.go:171","msg":"trace[561245950] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"253.559628ms","start":"2026-08-11T11:13:28.732378Z","end":"2026-08-11T11:13:28.985937Z","steps":["trace[561245950] 'read index received' (duration: 90.42855ms)","trace[561245950] 'applied index is now lower than readState.Index' (duration: 163.129868ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:28.986076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.691331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:13:28.986117Z","caller":"traceutil/trace.go:171","msg":"trace[546913683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"253.762593ms","start":"2026-08-11T11:13:28.732344Z","end":"2026-08-11T11:13:28.986107Z","steps":["trace[546913683] 'agreement among raft nodes before linearized reading' (duration: 253.64376ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:28.986275Z","caller":"traceutil/trace.go:171","msg":"trace[1490570651] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"426.877481ms","start":"2026-08-11T11:13:28.559365Z","end":"2026-08-11T11:13:28.986242Z","steps":["trace[1490570651] 'process raft request' (duration: 263.506577ms)","trace[1490570651] 'compare' (duration: 162.704887ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:28.986434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:28.559342Z","time spent":"427.015795ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:13:29.053882Z","caller":"traceutil/trace.go:171","msg":"trace[816752894] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"281.824985ms","start":"2026-08-11T11:13:28.772026Z","end":"2026-08-11T11:13:29.05385Z","steps":["trace[816752894] 'process raft request' (duration: 281.088026ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:29.552866Z","caller":"traceutil/trace.go:171","msg":"trace[1324692849] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"379.006944ms","start":"2026-08-11T11:13:29.17384Z","end":"2026-08-11T11:13:29.552847Z","steps":["trace[1324692849] 'read index received' (duration: 378.810929ms)","trace[1324692849] 'applied index is now lower than readState.Index' (duration: 194.935µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:13:29.552943Z","caller":"traceutil/trace.go:171","msg":"trace[1409880248] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"428.383098ms","start":"2026-08-11T11:13:29.124514Z","end":"2026-08-11T11:13:29.552897Z","steps":["trace[1409880248] 'process raft request' (duration: 428.207244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:29.553109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:29.124489Z","time spent":"428.553062ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:13:29.553138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.24626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vtjbl\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-11T11:13:29.55317Z","caller":"traceutil/trace.go:171","msg":"trace[308972937] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vtjbl; range_end:; response_count:1; response_revision:647; }","duration":"379.371083ms","start":"2026-08-11T11:13:29.173789Z","end":"2026-08-11T11:13:29.55316Z","steps":["trace[308972937] 'agreement among raft nodes before linearized reading' (duration: 379.167148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:29.553157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.642214ms","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-08-11T11:13:29.553201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:29.173764Z","time spent":"379.429864ms","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-vtjbl\" "} {"level":"info","ts":"2026-08-11T11:13:29.553212Z","caller":"traceutil/trace.go:171","msg":"trace[1579583504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"278.741237ms","start":"2026-08-11T11:13:29.274455Z","end":"2026-08-11T11:13:29.553197Z","steps":["trace[1579583504] 'agreement among raft nodes before linearized reading' (duration: 278.541962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:29.55329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.971678ms","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-08-11T11:13:29.553356Z","caller":"traceutil/trace.go:171","msg":"trace[259603465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"258.05076ms","start":"2026-08-11T11:13:29.295288Z","end":"2026-08-11T11:13:29.553339Z","steps":["trace[259603465] 'agreement among raft nodes before linearized reading' (duration: 257.808134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:29.966197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.043296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:13:29.966474Z","caller":"traceutil/trace.go:171","msg":"trace[1736099392] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:670; }","duration":"356.929342ms","start":"2026-08-11T11:13:29.609525Z","end":"2026-08-11T11:13:29.966454Z","steps":["trace[1736099392] 'read index received' (duration: 61.411395ms)","trace[1736099392] 'applied index is now lower than readState.Index' (duration: 295.516877ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:13:29.966517Z","caller":"traceutil/trace.go:171","msg":"trace[1156619959] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"392.153123ms","start":"2026-08-11T11:13:29.574338Z","end":"2026-08-11T11:13:29.966491Z","steps":["trace[1156619959] 'process raft request' (duration: 391.978559ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:29.966563Z","caller":"traceutil/trace.go:171","msg":"trace[1103723201] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"406.747898ms","start":"2026-08-11T11:13:29.559783Z","end":"2026-08-11T11:13:29.966531Z","steps":["trace[1103723201] 'process raft request' (duration: 111.241131ms)","trace[1103723201] 'compare' (duration: 294.909532ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:29.966608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.075436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-11T11:13:29.966646Z","caller":"traceutil/trace.go:171","msg":"trace[774052884] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:650; }","duration":"357.137628ms","start":"2026-08-11T11:13:29.609496Z","end":"2026-08-11T11:13:29.966633Z","steps":["trace[774052884] 'agreement among raft nodes before linearized reading' (duration: 357.067826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:29.966655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:29.574317Z","time spent":"392.260525ms","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:13:29.966682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:29.609482Z","time spent":"357.191129ms","remote":"127.0.0.1:44174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-08-11T11:13:29.966708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:29.559773Z","time spent":"406.858571ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:13:29.966771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.141568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:13:29.966827Z","caller":"traceutil/trace.go:171","msg":"trace[748017639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"233.24377ms","start":"2026-08-11T11:13:29.733569Z","end":"2026-08-11T11:13:29.966813Z","steps":["trace[748017639] 'agreement among raft nodes before linearized reading' (duration: 233.153958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:30.995925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.096658ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:13:30.996049Z","caller":"traceutil/trace.go:171","msg":"trace[197436138] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:650; }","duration":"762.246412ms","start":"2026-08-11T11:13:30.233784Z","end":"2026-08-11T11:13:30.99603Z","steps":["trace[197436138] 'range keys from in-memory index tree' (duration: 762.075158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:30.99958Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.023178985s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T11:13:31.000226Z","caller":"traceutil/trace.go:171","msg":"trace[786635228] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.023929713s","start":"2026-08-11T11:13:29.976275Z","end":"2026-08-11T11:13:31.000205Z","steps":["trace[786635228] 'process raft request' (duration: 1.02380509s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:31.000402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:29.976257Z","time spent":"1.024083298s","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:13:31.002325Z","caller":"traceutil/trace.go:171","msg":"trace[823325135] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:672; }","duration":"437.082793ms","start":"2026-08-11T11:13:30.565224Z","end":"2026-08-11T11:13:31.002307Z","steps":["trace[823325135] 'read index received' (duration: 434.894778ms)","trace[823325135] 'applied index is now lower than readState.Index' (duration: 2.176755ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:31.00262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.379801ms","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-08-11T11:13:31.002763Z","caller":"traceutil/trace.go:171","msg":"trace[36944626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"437.507243ms","start":"2026-08-11T11:13:30.565179Z","end":"2026-08-11T11:13:31.002687Z","steps":["trace[36944626] 'agreement among raft nodes before linearized reading' (duration: 437.222346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:31.002808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:30.565161Z","time spent":"437.637267ms","remote":"127.0.0.1:44224","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-08-11T11:13:32.566485Z","caller":"traceutil/trace.go:171","msg":"trace[437843889] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"263.053266ms","start":"2026-08-11T11:13:32.303402Z","end":"2026-08-11T11:13:32.566455Z","steps":["trace[437843889] 'process raft request' (duration: 262.851621ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:33.373786Z","caller":"traceutil/trace.go:171","msg":"trace[1871103887] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"153.181859ms","start":"2026-08-11T11:13:33.220574Z","end":"2026-08-11T11:13:33.373755Z","steps":["trace[1871103887] 'process raft request' (duration: 99.537368ms)","trace[1871103887] 'compare' (duration: 53.514568ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:13:33.490044Z","caller":"traceutil/trace.go:171","msg":"trace[1973989000] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"100.077052ms","start":"2026-08-11T11:13:33.38994Z","end":"2026-08-11T11:13:33.490017Z","steps":["trace[1973989000] 'process raft request' (duration: 99.935118ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:34.409471Z","caller":"traceutil/trace.go:171","msg":"trace[363497260] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"178.129833ms","start":"2026-08-11T11:13:34.231285Z","end":"2026-08-11T11:13:34.409414Z","steps":["trace[363497260] 'process raft request' (duration: 113.976769ms)","trace[363497260] 'compare' (duration: 64.022811ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:13:34.428729Z","caller":"traceutil/trace.go:171","msg":"trace[705524] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:735; }","duration":"129.140148ms","start":"2026-08-11T11:13:34.299556Z","end":"2026-08-11T11:13:34.428697Z","steps":["trace[705524] 'read index received' (duration: 45.786954ms)","trace[705524] 'applied index is now lower than readState.Index' (duration: 83.351774ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:34.429047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.456227ms","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-08-11T11:13:34.429086Z","caller":"traceutil/trace.go:171","msg":"trace[800168362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:714; }","duration":"129.57696ms","start":"2026-08-11T11:13:34.299495Z","end":"2026-08-11T11:13:34.429072Z","steps":["trace[800168362] 'agreement among raft nodes before linearized reading' (duration: 129.322153ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:34.429125Z","caller":"traceutil/trace.go:171","msg":"trace[946108125] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"131.072357ms","start":"2026-08-11T11:13:34.298012Z","end":"2026-08-11T11:13:34.429085Z","steps":["trace[946108125] 'process raft request' (duration: 130.430661ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:34.583653Z","caller":"traceutil/trace.go:171","msg":"trace[1686468291] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"139.848026ms","start":"2026-08-11T11:13:34.443768Z","end":"2026-08-11T11:13:34.583616Z","steps":["trace[1686468291] 'process raft request' (duration: 87.157328ms)","trace[1686468291] 'compare' (duration: 52.350549ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:13:45.621834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.373833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.83\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-11T11:13:45.621901Z","caller":"traceutil/trace.go:171","msg":"trace[1690856430] range","detail":"{range_begin:/registry/masterleases/199.19.213.83; range_end:; response_count:1; response_revision:805; }","duration":"407.495117ms","start":"2026-08-11T11:13:45.214393Z","end":"2026-08-11T11:13:45.621888Z","steps":["trace[1690856430] 'range keys from in-memory index tree' (duration: 407.205189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:45.621896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.749718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bsz6d\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-08-11T11:13:45.621928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:45.214366Z","time spent":"407.554658ms","remote":"127.0.0.1:44004","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.83\" "} {"level":"info","ts":"2026-08-11T11:13:45.621955Z","caller":"traceutil/trace.go:171","msg":"trace[842868354] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bsz6d; range_end:; response_count:1; response_revision:805; }","duration":"348.84589ms","start":"2026-08-11T11:13:45.273091Z","end":"2026-08-11T11:13:45.621937Z","steps":["trace[842868354] 'range keys from in-memory index tree' (duration: 348.564083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:45.622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:45.27307Z","time spent":"348.917763ms","remote":"127.0.0.1:44158","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-bsz6d\" "} {"level":"warn","ts":"2026-08-11T11:13:46.021197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:45.622928Z","time spent":"398.264736ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-11T11:13:46.126728Z","caller":"traceutil/trace.go:171","msg":"trace[1659945209] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"390.643336ms","start":"2026-08-11T11:13:45.736062Z","end":"2026-08-11T11:13:46.126705Z","steps":["trace[1659945209] 'read index received' (duration: 284.953924ms)","trace[1659945209] 'applied index is now lower than readState.Index' (duration: 105.688001ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:13:46.126815Z","caller":"traceutil/trace.go:171","msg":"trace[511284305] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"497.023134ms","start":"2026-08-11T11:13:45.629755Z","end":"2026-08-11T11:13:46.126778Z","steps":["trace[511284305] 'process raft request' (duration: 496.768808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:46.126996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.879411ms","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-08-11T11:13:46.127086Z","caller":"traceutil/trace.go:171","msg":"trace[1172232069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"391.059986ms","start":"2026-08-11T11:13:45.736012Z","end":"2026-08-11T11:13:46.127072Z","steps":["trace[1172232069] 'agreement among raft nodes before linearized reading' (duration: 390.82543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:13:46.127134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:45.629731Z","time spent":"497.172128ms","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:13:46.127154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:13:45.735993Z","time spent":"391.149247ms","remote":"127.0.0.1:44224","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-08-11T11:13:46.128896Z","caller":"traceutil/trace.go:171","msg":"trace[2009107897] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"106.265236ms","start":"2026-08-11T11:13:46.022613Z","end":"2026-08-11T11:13:46.128878Z","steps":["trace[2009107897] 'process raft request' (duration: 106.03133ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:53.795509Z","caller":"traceutil/trace.go:171","msg":"trace[985909766] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"146.903202ms","start":"2026-08-11T11:13:53.648587Z","end":"2026-08-11T11:13:53.79549Z","steps":["trace[985909766] 'process raft request' (duration: 146.558723ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:54.291302Z","caller":"traceutil/trace.go:171","msg":"trace[26715743] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"123.271712ms","start":"2026-08-11T11:13:54.167895Z","end":"2026-08-11T11:13:54.291167Z","steps":["trace[26715743] 'process raft request' (duration: 123.018925ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:13:54.324361Z","caller":"traceutil/trace.go:171","msg":"trace[1000338373] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"152.836671ms","start":"2026-08-11T11:13:54.171504Z","end":"2026-08-11T11:13:54.32434Z","steps":["trace[1000338373] 'process raft request' (duration: 152.721128ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:20.771759Z","caller":"traceutil/trace.go:171","msg":"trace[462684476] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"103.418485ms","start":"2026-08-11T11:14:20.66831Z","end":"2026-08-11T11:14:20.771729Z","steps":["trace[462684476] 'process raft request' (duration: 103.235571ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:29.540579Z","caller":"traceutil/trace.go:171","msg":"trace[946303230] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"112.972984ms","start":"2026-08-11T11:14:29.427568Z","end":"2026-08-11T11:14:29.540541Z","steps":["trace[946303230] 'process raft request' (duration: 97.722343ms)","trace[946303230] 'compare' (duration: 15.105587ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:29.683256Z","caller":"traceutil/trace.go:171","msg":"trace[1515420276] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"117.2191ms","start":"2026-08-11T11:14:29.566015Z","end":"2026-08-11T11:14:29.683234Z","steps":["trace[1515420276] 'process raft request' (duration: 99.893907ms)","trace[1515420276] 'compare' (duration: 17.232081ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:14:29.974017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.499282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:14:29.974239Z","caller":"traceutil/trace.go:171","msg":"trace[1037846485] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"245.131038ms","start":"2026-08-11T11:14:29.729065Z","end":"2026-08-11T11:14:29.974196Z","steps":["trace[1037846485] 'process raft request' (duration: 54.363959ms)","trace[1037846485] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-node-lease/instance; req_size:518; } (duration: 190.343888ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:44.895264Z","caller":"traceutil/trace.go:171","msg":"trace[1259210651] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"110.085002ms","start":"2026-08-11T11:14:44.785153Z","end":"2026-08-11T11:14:44.895238Z","steps":["trace[1259210651] 'process raft request' (duration: 54.869862ms)","trace[1259210651] 'compare' (duration: 55.092617ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:47.787943Z","caller":"traceutil/trace.go:171","msg":"trace[1544838204] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"166.103202ms","start":"2026-08-11T11:14:47.621812Z","end":"2026-08-11T11:14:47.787915Z","steps":["trace[1544838204] 'process raft request' (duration: 165.824345ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:14:48.009478Z","caller":"traceutil/trace.go:171","msg":"trace[1777244666] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1153; }","duration":"143.268262ms","start":"2026-08-11T11:14:47.866172Z","end":"2026-08-11T11:14:48.00944Z","steps":["trace[1777244666] 'read index received' (duration: 49.254642ms)","trace[1777244666] 'applied index is now lower than readState.Index' (duration: 94.01224ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:14:48.009555Z","caller":"traceutil/trace.go:171","msg":"trace[1547047484] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"158.958734ms","start":"2026-08-11T11:14:47.850572Z","end":"2026-08-11T11:14:48.00953Z","steps":["trace[1547047484] 'process raft request' (duration: 64.814681ms)","trace[1547047484] 'compare' (duration: 93.870276ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:14:48.009718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.528788ms","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-08-11T11:14:48.009769Z","caller":"traceutil/trace.go:171","msg":"trace[973881972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1113; }","duration":"143.618671ms","start":"2026-08-11T11:14:47.866132Z","end":"2026-08-11T11:14:48.009751Z","steps":["trace[973881972] 'agreement among raft nodes before linearized reading' (duration: 143.434036ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:25.522224Z","caller":"traceutil/trace.go:171","msg":"trace[879482123] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"230.440731ms","start":"2026-08-11T11:15:25.291743Z","end":"2026-08-11T11:15:25.522183Z","steps":["trace[879482123] 'process raft request' (duration: 135.689942ms)","trace[879482123] 'compare' (duration: 94.456531ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:15:25.522472Z","caller":"traceutil/trace.go:171","msg":"trace[18296267] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"145.289732ms","start":"2026-08-11T11:15:25.377144Z","end":"2026-08-11T11:15:25.522434Z","steps":["trace[18296267] 'process raft request' (duration: 145.176729ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:35.68973Z","caller":"traceutil/trace.go:171","msg":"trace[113191614] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"101.875127ms","start":"2026-08-11T11:15:35.587824Z","end":"2026-08-11T11:15:35.6897Z","steps":["trace[113191614] 'process raft request' (duration: 101.671201ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:35.907838Z","caller":"traceutil/trace.go:171","msg":"trace[1210305405] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"173.486517ms","start":"2026-08-11T11:15:35.734328Z","end":"2026-08-11T11:15:35.907814Z","steps":["trace[1210305405] 'read index received' (duration: 106.368019ms)","trace[1210305405] 'applied index is now lower than readState.Index' (duration: 67.117448ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:35.907976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.647782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:15:35.908008Z","caller":"traceutil/trace.go:171","msg":"trace[369092457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"173.733174ms","start":"2026-08-11T11:15:35.734264Z","end":"2026-08-11T11:15:35.907997Z","steps":["trace[369092457] 'agreement among raft nodes before linearized reading' (duration: 173.643221ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:35.908295Z","caller":"traceutil/trace.go:171","msg":"trace[431348123] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"213.834596ms","start":"2026-08-11T11:15:35.694441Z","end":"2026-08-11T11:15:35.908276Z","steps":["trace[431348123] 'process raft request' (duration: 146.556714ms)","trace[431348123] 'compare' (duration: 66.710987ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:15:37.476175Z","caller":"traceutil/trace.go:171","msg":"trace[210938934] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"138.742758ms","start":"2026-08-11T11:15:37.337401Z","end":"2026-08-11T11:15:37.476144Z","steps":["trace[210938934] 'process raft request' (duration: 88.348179ms)","trace[210938934] 'compare' (duration: 50.076441ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:15:44.125878Z","caller":"traceutil/trace.go:171","msg":"trace[1842596590] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"127.336184ms","start":"2026-08-11T11:15:43.998515Z","end":"2026-08-11T11:15:44.125852Z","steps":["trace[1842596590] 'process raft request' (duration: 127.1897ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:44.409403Z","caller":"traceutil/trace.go:171","msg":"trace[1142892428] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"278.672516ms","start":"2026-08-11T11:15:44.130702Z","end":"2026-08-11T11:15:44.409374Z","steps":["trace[1142892428] 'process raft request' (duration: 277.611969ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:51.619565Z","caller":"traceutil/trace.go:171","msg":"trace[92163664] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"184.669977ms","start":"2026-08-11T11:15:51.434871Z","end":"2026-08-11T11:15:51.619541Z","steps":["trace[92163664] 'process raft request' (duration: 184.494873ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:56.866072Z","caller":"traceutil/trace.go:171","msg":"trace[1413038358] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"131.906658ms","start":"2026-08-11T11:15:56.734144Z","end":"2026-08-11T11:15:56.866051Z","steps":["trace[1413038358] 'read index received' (duration: 131.790205ms)","trace[1413038358] 'applied index is now lower than readState.Index' (duration: 115.203µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:15:56.86618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.01899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:15:56.866173Z","caller":"traceutil/trace.go:171","msg":"trace[404928988] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"151.557499ms","start":"2026-08-11T11:15:56.71458Z","end":"2026-08-11T11:15:56.866137Z","steps":["trace[404928988] 'process raft request' (duration: 151.311963ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:56.866214Z","caller":"traceutil/trace.go:171","msg":"trace[74898215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1337; }","duration":"132.106842ms","start":"2026-08-11T11:15:56.734098Z","end":"2026-08-11T11:15:56.866205Z","steps":["trace[74898215] 'agreement among raft nodes before linearized reading' (duration: 132.033571ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:15:57.751991Z","caller":"traceutil/trace.go:171","msg":"trace[942018187] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"108.956954ms","start":"2026-08-11T11:15:57.642981Z","end":"2026-08-11T11:15:57.751938Z","steps":["trace[942018187] 'process raft request' (duration: 108.77967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:04.341603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.754988ms","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-08-11T11:16:04.341712Z","caller":"traceutil/trace.go:171","msg":"trace[1216166936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1359; }","duration":"408.943293ms","start":"2026-08-11T11:16:03.932754Z","end":"2026-08-11T11:16:04.341697Z","steps":["trace[1216166936] 'range keys from in-memory index tree' (duration: 408.575713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:04.341745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:03.932735Z","time spent":"409.002404ms","remote":"127.0.0.1:44224","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-08-11T11:16:04.859031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700325608761639701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:16:05.35994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700325608761639701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:16:05.531182Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.185805594s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T11:16:05.531598Z","caller":"traceutil/trace.go:171","msg":"trace[1823582987] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"1.186278125s","start":"2026-08-11T11:16:04.345292Z","end":"2026-08-11T11:16:05.53157Z","steps":["trace[1823582987] 'process raft request' (duration: 1.186113201s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:05.531816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:04.345274Z","time spent":"1.186394188s","remote":"127.0.0.1:44224","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-08-11T11:16:05.545192Z","caller":"traceutil/trace.go:171","msg":"trace[942117644] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1415; }","duration":"1.186712586s","start":"2026-08-11T11:16:04.358464Z","end":"2026-08-11T11:16:05.545177Z","steps":["trace[942117644] 'read index received' (duration: 1.17289397s)","trace[942117644] 'applied index is now lower than readState.Index' (duration: 13.817916ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:05.545391Z","caller":"traceutil/trace.go:171","msg":"trace[987369965] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"862.236915ms","start":"2026-08-11T11:16:04.683139Z","end":"2026-08-11T11:16:05.545376Z","steps":["trace[987369965] 'process raft request' (duration: 861.962028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:05.545475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:04.683122Z","time spent":"862.301966ms","remote":"127.0.0.1:44224","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-08-11T11:16:05.545474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.557767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:16:05.545558Z","caller":"traceutil/trace.go:171","msg":"trace[152604701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"811.688771ms","start":"2026-08-11T11:16:04.733847Z","end":"2026-08-11T11:16:05.545536Z","steps":["trace[152604701] 'agreement among raft nodes before linearized reading' (duration: 811.544607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:05.545583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.144008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.83\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-08-11T11:16:05.545574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.86299ms","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-08-11T11:16:05.545607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:04.733826Z","time spent":"811.769933ms","remote":"127.0.0.1:43964","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-08-11T11:16:05.545616Z","caller":"traceutil/trace.go:171","msg":"trace[1382970703] range","detail":"{range_begin:/registry/masterleases/199.19.213.83; range_end:; response_count:1; response_revision:1361; }","duration":"323.200729ms","start":"2026-08-11T11:16:05.222403Z","end":"2026-08-11T11:16:05.545604Z","steps":["trace[1382970703] 'agreement among raft nodes before linearized reading' (duration: 323.126027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:05.545645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:05.222387Z","time spent":"323.251171ms","remote":"127.0.0.1:44004","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.83\" "} {"level":"info","ts":"2026-08-11T11:16:05.545645Z","caller":"traceutil/trace.go:171","msg":"trace[202250758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1361; }","duration":"639.006275ms","start":"2026-08-11T11:16:04.906608Z","end":"2026-08-11T11:16:05.545614Z","steps":["trace[202250758] 'agreement among raft nodes before linearized reading' (duration: 638.857871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:05.545681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:04.906593Z","time spent":"639.079466ms","remote":"127.0.0.1:44224","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-08-11T11:16:05.545722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.479596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:16:05.545497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187017823s","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-08-11T11:16:05.545753Z","caller":"traceutil/trace.go:171","msg":"trace[1037942853] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1361; }","duration":"769.543567ms","start":"2026-08-11T11:16:04.776201Z","end":"2026-08-11T11:16:05.545744Z","steps":["trace[1037942853] 'agreement among raft nodes before linearized reading' (duration: 769.493566ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:05.545776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:04.776185Z","time spent":"769.584968ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-08-11T11:16:05.545767Z","caller":"traceutil/trace.go:171","msg":"trace[524198130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1361; }","duration":"1.187329151s","start":"2026-08-11T11:16:04.358426Z","end":"2026-08-11T11:16:05.545755Z","steps":["trace[524198130] 'agreement among raft nodes before linearized reading' (duration: 1.186957332s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:05.545809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:04.358412Z","time spent":"1.187382713s","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T11:16:05.546011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.326877ms","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-08-11T11:16:05.546053Z","caller":"traceutil/trace.go:171","msg":"trace[354082418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1361; }","duration":"468.42849ms","start":"2026-08-11T11:16:05.077612Z","end":"2026-08-11T11:16:05.546041Z","steps":["trace[354082418] 'agreement among raft nodes before linearized reading' (duration: 467.813555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:05.546078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:05.077579Z","time spent":"468.492022ms","remote":"127.0.0.1:44224","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-08-11T11:16:24.274846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.087241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:16:24.274924Z","caller":"traceutil/trace.go:171","msg":"trace[1483297164] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1480; }","duration":"637.613989ms","start":"2026-08-11T11:16:23.637298Z","end":"2026-08-11T11:16:24.274912Z","steps":["trace[1483297164] 'read index received' (duration: 109.338693ms)","trace[1483297164] 'applied index is now lower than readState.Index' (duration: 528.274416ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:24.275094Z","caller":"traceutil/trace.go:171","msg":"trace[1628401893] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"646.795168ms","start":"2026-08-11T11:16:23.628276Z","end":"2026-08-11T11:16:24.275072Z","steps":["trace[1628401893] 'process raft request' (duration: 118.41974ms)","trace[1628401893] 'compare' (duration: 527.939427ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:16:24.275141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:23.628259Z","time spent":"646.85717ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:16:24.275322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.023469ms","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-08-11T11:16:24.275349Z","caller":"traceutil/trace.go:171","msg":"trace[179511085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1421; }","duration":"638.07617ms","start":"2026-08-11T11:16:23.637265Z","end":"2026-08-11T11:16:24.275341Z","steps":["trace[179511085] 'agreement among raft nodes before linearized reading' (duration: 637.988778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:24.275393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:23.637249Z","time spent":"638.114362ms","remote":"127.0.0.1:44224","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-08-11T11:16:24.27553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.714852ms","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-08-11T11:16:24.275612Z","caller":"traceutil/trace.go:171","msg":"trace[1075468728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1421; }","duration":"635.820514ms","start":"2026-08-11T11:16:23.639774Z","end":"2026-08-11T11:16:24.275594Z","steps":["trace[1075468728] 'agreement among raft nodes before linearized reading' (duration: 635.564058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:24.275651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:23.639767Z","time spent":"635.874646ms","remote":"127.0.0.1:44224","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-08-11T11:16:24.275656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.573413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:16:24.27568Z","caller":"traceutil/trace.go:171","msg":"trace[2021871395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1421; }","duration":"540.627294ms","start":"2026-08-11T11:16:23.735047Z","end":"2026-08-11T11:16:24.275674Z","steps":["trace[2021871395] 'agreement among raft nodes before linearized reading' (duration: 540.588083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:24.275699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:23.735032Z","time spent":"540.661106ms","remote":"127.0.0.1:43964","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-08-11T11:16:24.275852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.130428ms","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-08-11T11:16:24.275875Z","caller":"traceutil/trace.go:171","msg":"trace[1696543043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1421; }","duration":"305.176688ms","start":"2026-08-11T11:16:23.970693Z","end":"2026-08-11T11:16:24.27587Z","steps":["trace[1696543043] 'agreement among raft nodes before linearized reading' (duration: 305.117077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:24.275876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.785943ms","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-08-11T11:16:24.275917Z","caller":"traceutil/trace.go:171","msg":"trace[1890939823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1421; }","duration":"631.839804ms","start":"2026-08-11T11:16:23.644065Z","end":"2026-08-11T11:16:24.275905Z","steps":["trace[1890939823] 'agreement among raft nodes before linearized reading' (duration: 631.739072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:24.275963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:23.644057Z","time spent":"631.888536ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T11:16:24.275892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:23.970681Z","time spent":"305.20651ms","remote":"127.0.0.1:44224","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-08-11T11:16:25.69566Z","caller":"traceutil/trace.go:171","msg":"trace[86916230] linearizableReadLoop","detail":"{readStateIndex:1486; appliedIndex:1485; }","duration":"146.318792ms","start":"2026-08-11T11:16:25.549321Z","end":"2026-08-11T11:16:25.69564Z","steps":["trace[86916230] 'read index received' (duration: 146.229219ms)","trace[86916230] 'applied index is now lower than readState.Index' (duration: 88.142µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:16:25.695696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:25.2239Z","time spent":"471.791678ms","remote":"127.0.0.1:44004","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-11T11:16:25.69577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.429454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:16:25.695799Z","caller":"traceutil/trace.go:171","msg":"trace[1136991335] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1425; }","duration":"146.509546ms","start":"2026-08-11T11:16:25.549281Z","end":"2026-08-11T11:16:25.69579Z","steps":["trace[1136991335] 'agreement among raft nodes before linearized reading' (duration: 146.430684ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:25.821474Z","caller":"traceutil/trace.go:171","msg":"trace[2028519153] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"191.590877ms","start":"2026-08-11T11:16:25.629851Z","end":"2026-08-11T11:16:25.821442Z","steps":["trace[2028519153] 'process raft request' (duration: 188.636931ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:25.821554Z","caller":"traceutil/trace.go:171","msg":"trace[1592267785] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"124.321706ms","start":"2026-08-11T11:16:25.697175Z","end":"2026-08-11T11:16:25.821496Z","steps":["trace[1592267785] 'process raft request' (duration: 124.143311ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:28.750534Z","caller":"traceutil/trace.go:171","msg":"trace[714750245] linearizableReadLoop","detail":"{readStateIndex:1494; appliedIndex:1493; }","duration":"406.758217ms","start":"2026-08-11T11:16:28.343752Z","end":"2026-08-11T11:16:28.75051Z","steps":["trace[714750245] 'read index received' (duration: 406.554732ms)","trace[714750245] 'applied index is now lower than readState.Index' (duration: 202.515µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:28.750641Z","caller":"traceutil/trace.go:171","msg":"trace[1894784548] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"922.712618ms","start":"2026-08-11T11:16:27.827892Z","end":"2026-08-11T11:16:28.750605Z","steps":["trace[1894784548] 'process raft request' (duration: 922.435711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:28.750779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:27.827859Z","time spent":"922.844771ms","remote":"127.0.0.1:44224","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-08-11T11:16:28.750837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.049185ms","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-08-11T11:16:28.750876Z","caller":"traceutil/trace.go:171","msg":"trace[2009101929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1433; }","duration":"407.154898ms","start":"2026-08-11T11:16:28.34371Z","end":"2026-08-11T11:16:28.750865Z","steps":["trace[2009101929] 'agreement among raft nodes before linearized reading' (duration: 406.972613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:28.750917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:28.343693Z","time spent":"407.215969ms","remote":"127.0.0.1:44224","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-08-11T11:16:28.754981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.342369ms","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-08-11T11:16:28.755048Z","caller":"traceutil/trace.go:171","msg":"trace[539373658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1434; }","duration":"365.454502ms","start":"2026-08-11T11:16:28.38958Z","end":"2026-08-11T11:16:28.755034Z","steps":["trace[539373658] 'agreement among raft nodes before linearized reading' (duration: 365.271508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:28.755101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:28.389562Z","time spent":"365.531374ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-11T11:16:28.755259Z","caller":"traceutil/trace.go:171","msg":"trace[2047184931] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"390.437993ms","start":"2026-08-11T11:16:28.36481Z","end":"2026-08-11T11:16:28.755248Z","steps":["trace[2047184931] 'process raft request' (duration: 389.93924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:28.75534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:28.364783Z","time spent":"390.504915ms","remote":"127.0.0.1:44224","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-08-11T11:16:28.75609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.939803ms","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-08-11T11:16:28.756126Z","caller":"traceutil/trace.go:171","msg":"trace[1762348211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1434; }","duration":"300.989344ms","start":"2026-08-11T11:16:28.455127Z","end":"2026-08-11T11:16:28.756116Z","steps":["trace[1762348211] 'agreement among raft nodes before linearized reading' (duration: 300.910082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:28.756152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:28.455119Z","time spent":"301.026735ms","remote":"127.0.0.1:44224","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-08-11T11:16:28.75627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.163849ms","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-08-11T11:16:28.756329Z","caller":"traceutil/trace.go:171","msg":"trace[1333103083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1434; }","duration":"311.251872ms","start":"2026-08-11T11:16:28.445062Z","end":"2026-08-11T11:16:28.756314Z","steps":["trace[1333103083] 'agreement among raft nodes before linearized reading' (duration: 311.114698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:28.756371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:28.445046Z","time spent":"311.315123ms","remote":"127.0.0.1:44224","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-08-11T11:16:28.756518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.10773ms","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-08-11T11:16:28.756555Z","caller":"traceutil/trace.go:171","msg":"trace[978872576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1434; }","duration":"303.176531ms","start":"2026-08-11T11:16:28.45337Z","end":"2026-08-11T11:16:28.756547Z","steps":["trace[978872576] 'agreement among raft nodes before linearized reading' (duration: 303.098159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:28.756603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:28.453356Z","time spent":"303.236893ms","remote":"127.0.0.1:44224","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-08-11T11:16:28.906804Z","caller":"traceutil/trace.go:171","msg":"trace[1692451569] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1495; }","duration":"149.972174ms","start":"2026-08-11T11:16:28.756792Z","end":"2026-08-11T11:16:28.906764Z","steps":["trace[1692451569] 'read index received' (duration: 136.660327ms)","trace[1692451569] 'applied index is now lower than readState.Index' (duration: 13.310757ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:28.906881Z","caller":"traceutil/trace.go:171","msg":"trace[1764308213] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"151.74331ms","start":"2026-08-11T11:16:28.755103Z","end":"2026-08-11T11:16:28.906846Z","steps":["trace[1764308213] 'process raft request' (duration: 138.065184ms)","trace[1764308213] 'compare' (duration: 13.415119ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:16:28.906959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.138298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:16:28.907Z","caller":"traceutil/trace.go:171","msg":"trace[350429356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1435; }","duration":"150.219171ms","start":"2026-08-11T11:16:28.756772Z","end":"2026-08-11T11:16:28.906991Z","steps":["trace[350429356] 'agreement among raft nodes before linearized reading' (duration: 150.091548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:28.913567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.356759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:16:28.913637Z","caller":"traceutil/trace.go:171","msg":"trace[714857044] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1439; }","duration":"131.473832ms","start":"2026-08-11T11:16:28.782147Z","end":"2026-08-11T11:16:28.91362Z","steps":["trace[714857044] 'agreement among raft nodes before linearized reading' (duration: 131.280897ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:28.91388Z","caller":"traceutil/trace.go:171","msg":"trace[1231813488] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"153.126776ms","start":"2026-08-11T11:16:28.760744Z","end":"2026-08-11T11:16:28.913871Z","steps":["trace[1231813488] 'process raft request' (duration: 152.654784ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:28.913882Z","caller":"traceutil/trace.go:171","msg":"trace[1713205111] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"154.387049ms","start":"2026-08-11T11:16:28.759469Z","end":"2026-08-11T11:16:28.913856Z","steps":["trace[1713205111] 'process raft request' (duration: 153.906766ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:28.913898Z","caller":"traceutil/trace.go:171","msg":"trace[425764677] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"154.43704ms","start":"2026-08-11T11:16:28.759431Z","end":"2026-08-11T11:16:28.913868Z","steps":["trace[425764677] 'process raft request' (duration: 153.806233ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:28.914274Z","caller":"traceutil/trace.go:171","msg":"trace[851902850] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"154.82149ms","start":"2026-08-11T11:16:28.759436Z","end":"2026-08-11T11:16:28.914257Z","steps":["trace[851902850] 'process raft request' (duration: 153.909116ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:29.305485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.291828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:16:29.305555Z","caller":"traceutil/trace.go:171","msg":"trace[955524425] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1439; }","duration":"277.418871ms","start":"2026-08-11T11:16:29.028118Z","end":"2026-08-11T11:16:29.305536Z","steps":["trace[955524425] 'count revisions from in-memory index tree' (duration: 277.170515ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:43.00113Z","caller":"traceutil/trace.go:171","msg":"trace[1829411962] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"127.288304ms","start":"2026-08-11T11:16:42.8738Z","end":"2026-08-11T11:16:43.001088Z","steps":["trace[1829411962] 'process raft request' (duration: 127.106069ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:43.001279Z","caller":"traceutil/trace.go:171","msg":"trace[1379858866] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"115.477946ms","start":"2026-08-11T11:16:42.885789Z","end":"2026-08-11T11:16:43.001267Z","steps":["trace[1379858866] 'process raft request' (duration: 115.276641ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:43.001167Z","caller":"traceutil/trace.go:171","msg":"trace[1063396600] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"124.718957ms","start":"2026-08-11T11:16:42.876411Z","end":"2026-08-11T11:16:43.00113Z","steps":["trace[1063396600] 'process raft request' (duration: 124.607274ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:43.024524Z","caller":"traceutil/trace.go:171","msg":"trace[1105949454] linearizableReadLoop","detail":"{readStateIndex:1582; appliedIndex:1578; }","duration":"113.538635ms","start":"2026-08-11T11:16:42.910934Z","end":"2026-08-11T11:16:43.024473Z","steps":["trace[1105949454] 'read index received' (duration: 90.123326ms)","trace[1105949454] 'applied index is now lower than readState.Index' (duration: 23.414109ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:43.024572Z","caller":"traceutil/trace.go:171","msg":"trace[491466727] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"118.227218ms","start":"2026-08-11T11:16:42.906298Z","end":"2026-08-11T11:16:43.024525Z","steps":["trace[491466727] 'process raft request' (duration: 118.117825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:43.024763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.809473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-29w4c\" ","response":"range_response_count:1 size:9695"} {"level":"info","ts":"2026-08-11T11:16:43.024608Z","caller":"traceutil/trace.go:171","msg":"trace[517545732] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"118.594997ms","start":"2026-08-11T11:16:42.90598Z","end":"2026-08-11T11:16:43.024575Z","steps":["trace[517545732] 'process raft request' (duration: 118.251678ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:43.024848Z","caller":"traceutil/trace.go:171","msg":"trace[1804911203] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-29w4c; range_end:; response_count:1; response_revision:1516; }","duration":"113.948477ms","start":"2026-08-11T11:16:42.910888Z","end":"2026-08-11T11:16:43.024836Z","steps":["trace[1804911203] 'agreement among raft nodes before linearized reading' (duration: 113.722721ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:43.024686Z","caller":"traceutil/trace.go:171","msg":"trace[926143740] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"115.398845ms","start":"2026-08-11T11:16:42.909229Z","end":"2026-08-11T11:16:43.024628Z","steps":["trace[926143740] 'process raft request' (duration: 115.2161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:43.040349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.519571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:9311"} {"level":"info","ts":"2026-08-11T11:16:43.040448Z","caller":"traceutil/trace.go:171","msg":"trace[1933483536] range","detail":"{range_begin:/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1519; }","duration":"124.651695ms","start":"2026-08-11T11:16:42.915774Z","end":"2026-08-11T11:16:43.040426Z","steps":["trace[1933483536] 'agreement among raft nodes before linearized reading' (duration: 124.47138ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:45.054544Z","caller":"traceutil/trace.go:171","msg":"trace[1584225603] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"182.256874ms","start":"2026-08-11T11:16:44.872264Z","end":"2026-08-11T11:16:45.054521Z","steps":["trace[1584225603] 'process raft request' (duration: 182.09709ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:47.383386Z","caller":"traceutil/trace.go:171","msg":"trace[655995518] linearizableReadLoop","detail":"{readStateIndex:1612; appliedIndex:1611; }","duration":"224.959015ms","start":"2026-08-11T11:16:47.158382Z","end":"2026-08-11T11:16:47.383341Z","steps":["trace[655995518] 'read index received' (duration: 224.902794ms)","trace[655995518] 'applied index is now lower than readState.Index' (duration: 55.491µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:47.383448Z","caller":"traceutil/trace.go:171","msg":"trace[1546420207] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"325.211055ms","start":"2026-08-11T11:16:47.058203Z","end":"2026-08-11T11:16:47.383414Z","steps":["trace[1546420207] 'process raft request' (duration: 325.0222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:47.383527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.12343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:16:47.383557Z","caller":"traceutil/trace.go:171","msg":"trace[1300581044] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1545; }","duration":"225.198092ms","start":"2026-08-11T11:16:47.15835Z","end":"2026-08-11T11:16:47.383548Z","steps":["trace[1300581044] 'agreement among raft nodes before linearized reading' (duration: 225.12608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:47.383643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.618204ms","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-08-11T11:16:47.383666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.335203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-08-11T11:16:47.383663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.573723ms","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-08-11T11:16:47.383692Z","caller":"traceutil/trace.go:171","msg":"trace[811889037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1545; }","duration":"192.693076ms","start":"2026-08-11T11:16:47.190986Z","end":"2026-08-11T11:16:47.383679Z","steps":["trace[811889037] 'agreement among raft nodes before linearized reading' (duration: 192.573733ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:47.383699Z","caller":"traceutil/trace.go:171","msg":"trace[1559050964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1545; }","duration":"193.401825ms","start":"2026-08-11T11:16:47.190289Z","end":"2026-08-11T11:16:47.38369Z","steps":["trace[1559050964] 'agreement among raft nodes before linearized reading' (duration: 193.302392ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:47.383708Z","caller":"traceutil/trace.go:171","msg":"trace[1184365019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1545; }","duration":"192.633395ms","start":"2026-08-11T11:16:47.191064Z","end":"2026-08-11T11:16:47.383697Z","steps":["trace[1184365019] 'agreement among raft nodes before linearized reading' (duration: 192.516012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:47.383793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.287851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-11T11:16:47.383766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.088576ms","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-08-11T11:16:47.383821Z","caller":"traceutil/trace.go:171","msg":"trace[1134227961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1545; }","duration":"198.347213ms","start":"2026-08-11T11:16:47.185467Z","end":"2026-08-11T11:16:47.383814Z","steps":["trace[1134227961] 'agreement among raft nodes before linearized reading' (duration: 198.028664ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:47.38385Z","caller":"traceutil/trace.go:171","msg":"trace[749877138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1545; }","duration":"193.197859ms","start":"2026-08-11T11:16:47.190634Z","end":"2026-08-11T11:16:47.383832Z","steps":["trace[749877138] 'agreement among raft nodes before linearized reading' (duration: 192.935452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:47.38389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:47.058176Z","time spent":"325.340159ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:16:48.035791Z","caller":"traceutil/trace.go:171","msg":"trace[524539981] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"648.181873ms","start":"2026-08-11T11:16:47.38759Z","end":"2026-08-11T11:16:48.035772Z","steps":["trace[524539981] 'process raft request' (duration: 648.05722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:48.035925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:47.387572Z","time spent":"648.283805ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:16:48.036207Z","caller":"traceutil/trace.go:171","msg":"trace[1304673998] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"647.941777ms","start":"2026-08-11T11:16:47.388246Z","end":"2026-08-11T11:16:48.036188Z","steps":["trace[1304673998] 'process raft request' (duration: 647.858275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:48.0363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:47.388226Z","time spent":"648.021659ms","remote":"127.0.0.1:44224","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-08-11T11:16:48.036356Z","caller":"traceutil/trace.go:171","msg":"trace[1722042988] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"648.038479ms","start":"2026-08-11T11:16:47.388307Z","end":"2026-08-11T11:16:48.036346Z","steps":["trace[1722042988] 'process raft request' (duration: 647.860405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:48.036409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:47.388302Z","time spent":"648.07874ms","remote":"127.0.0.1:44224","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-08-11T11:16:48.036449Z","caller":"traceutil/trace.go:171","msg":"trace[23791703] linearizableReadLoop","detail":"{readStateIndex:1617; appliedIndex:1613; }","duration":"199.788701ms","start":"2026-08-11T11:16:47.836652Z","end":"2026-08-11T11:16:48.03644Z","steps":["trace[23791703] 'read index received' (duration: 199.159654ms)","trace[23791703] 'applied index is now lower than readState.Index' (duration: 628.017µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:48.036472Z","caller":"traceutil/trace.go:171","msg":"trace[1903828198] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"648.06161ms","start":"2026-08-11T11:16:47.388404Z","end":"2026-08-11T11:16:48.036465Z","steps":["trace[1903828198] 'process raft request' (duration: 647.905776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:48.036492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.849163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:16:48.036504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:47.388398Z","time spent":"648.087981ms","remote":"127.0.0.1:44224","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-08-11T11:16:48.036513Z","caller":"traceutil/trace.go:171","msg":"trace[1061944354] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1550; }","duration":"199.907694ms","start":"2026-08-11T11:16:47.8366Z","end":"2026-08-11T11:16:48.036507Z","steps":["trace[1061944354] 'agreement among raft nodes before linearized reading' (duration: 199.864913ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:48.036554Z","caller":"traceutil/trace.go:171","msg":"trace[1500473170] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"645.733269ms","start":"2026-08-11T11:16:47.390815Z","end":"2026-08-11T11:16:48.036548Z","steps":["trace[1500473170] 'process raft request' (duration: 645.589506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:48.036605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:47.390805Z","time spent":"645.77658ms","remote":"127.0.0.1:44224","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-08-11T11:17:01.611766Z","caller":"traceutil/trace.go:171","msg":"trace[2048608003] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"179.189725ms","start":"2026-08-11T11:17:01.432558Z","end":"2026-08-11T11:17:01.611747Z","steps":["trace[2048608003] 'process raft request' (duration: 179.052541ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:17:02.424318Z","caller":"traceutil/trace.go:171","msg":"trace[2123518128] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"137.758216ms","start":"2026-08-11T11:17:02.286535Z","end":"2026-08-11T11:17:02.424294Z","steps":["trace[2123518128] 'process raft request' (duration: 76.720507ms)","trace[2123518128] 'compare' (duration: 60.783503ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:17:02.424397Z","caller":"traceutil/trace.go:171","msg":"trace[1776347422] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"136.74932ms","start":"2026-08-11T11:17:02.287631Z","end":"2026-08-11T11:17:02.424381Z","steps":["trace[1776347422] 'process raft request' (duration: 136.585925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:03.012813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.452929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:17:03.012884Z","caller":"traceutil/trace.go:171","msg":"trace[553935736] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1597; }","duration":"288.543301ms","start":"2026-08-11T11:17:02.724325Z","end":"2026-08-11T11:17:03.012868Z","steps":["trace[553935736] 'count revisions from in-memory index tree' (duration: 288.342616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:03.012858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.712753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:17:03.012939Z","caller":"traceutil/trace.go:171","msg":"trace[260560181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1597; }","duration":"281.838197ms","start":"2026-08-11T11:17:02.731081Z","end":"2026-08-11T11:17:03.012919Z","steps":["trace[260560181] 'range keys from in-memory index tree' (duration: 281.621701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:05.056422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.11549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:17:05.05673Z","caller":"traceutil/trace.go:171","msg":"trace[735001007] linearizableReadLoop","detail":"{readStateIndex:1675; appliedIndex:1673; }","duration":"626.480088ms","start":"2026-08-11T11:17:04.430228Z","end":"2026-08-11T11:17:05.056708Z","steps":["trace[735001007] 'read index received' (duration: 186.858784ms)","trace[735001007] 'applied index is now lower than readState.Index' (duration: 439.619654ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:17:05.056878Z","caller":"traceutil/trace.go:171","msg":"trace[941809932] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"682.976549ms","start":"2026-08-11T11:17:04.373888Z","end":"2026-08-11T11:17:05.056864Z","steps":["trace[941809932] 'process raft request' (duration: 682.694842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:05.05699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:04.373878Z","time spent":"683.04484ms","remote":"127.0.0.1:44224","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-08-11T11:17:05.057118Z","caller":"traceutil/trace.go:171","msg":"trace[1573203965] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"683.371249ms","start":"2026-08-11T11:17:04.373691Z","end":"2026-08-11T11:17:05.057062Z","steps":["trace[1573203965] 'process raft request' (duration: 243.383256ms)","trace[1573203965] 'compare' (duration: 438.73561ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:17:05.057363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.129725ms","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-08-11T11:17:05.05741Z","caller":"traceutil/trace.go:171","msg":"trace[636394892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1605; }","duration":"627.209207ms","start":"2026-08-11T11:17:04.430188Z","end":"2026-08-11T11:17:05.057398Z","steps":["trace[636394892] 'agreement among raft nodes before linearized reading' (duration: 627.049003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:05.057452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:04.430169Z","time spent":"627.275999ms","remote":"127.0.0.1:44224","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-08-11T11:17:05.057603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.904458ms","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-08-11T11:17:05.057658Z","caller":"traceutil/trace.go:171","msg":"trace[1545855300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1605; }","duration":"601.99973ms","start":"2026-08-11T11:17:04.455646Z","end":"2026-08-11T11:17:05.057646Z","steps":["trace[1545855300] 'agreement among raft nodes before linearized reading' (duration: 601.832146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:05.057684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:04.45563Z","time spent":"602.047912ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T11:17:05.057684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.215355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:17:05.057785Z","caller":"traceutil/trace.go:171","msg":"trace[2093658448] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1605; }","duration":"552.336878ms","start":"2026-08-11T11:17:04.505425Z","end":"2026-08-11T11:17:05.057762Z","steps":["trace[2093658448] 'agreement among raft nodes before linearized reading' (duration: 552.168074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:05.057752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.032477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-11T11:17:05.057849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:04.505372Z","time spent":"552.460911ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-08-11T11:17:05.057915Z","caller":"traceutil/trace.go:171","msg":"trace[595682874] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1605; }","duration":"583.15284ms","start":"2026-08-11T11:17:04.474678Z","end":"2026-08-11T11:17:05.057831Z","steps":["trace[595682874] 'agreement among raft nodes before linearized reading' (duration: 582.936644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:05.058112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:04.474663Z","time spent":"583.429627ms","remote":"127.0.0.1:44044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":126,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:17:05.057311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:04.373667Z","time spent":"683.563824ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:17:06.109417Z","caller":"traceutil/trace.go:171","msg":"trace[1581720055] transaction","detail":"{read_only:false; response_revision:1611; number_of_response:1; }","duration":"329.934079ms","start":"2026-08-11T11:17:05.779465Z","end":"2026-08-11T11:17:06.109399Z","steps":["trace[1581720055] 'process raft request' (duration: 329.831166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:06.10952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:05.779441Z","time spent":"330.034222ms","remote":"127.0.0.1:44044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":784,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:17:06.531662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.460819ms","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-08-11T11:17:06.531751Z","caller":"traceutil/trace.go:171","msg":"trace[423343117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1611; }","duration":"162.610053ms","start":"2026-08-11T11:17:06.369114Z","end":"2026-08-11T11:17:06.531724Z","steps":["trace[423343117] 'range keys from in-memory index tree' (duration: 162.287194ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:17:06.81413Z","caller":"traceutil/trace.go:171","msg":"trace[1035012628] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"278.315864ms","start":"2026-08-11T11:17:06.535777Z","end":"2026-08-11T11:17:06.814093Z","steps":["trace[1035012628] 'process raft request' (duration: 198.077366ms)","trace[1035012628] 'compare' (duration: 79.89761ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:17:06.908537Z","caller":"traceutil/trace.go:171","msg":"trace[1267872293] transaction","detail":"{read_only:false; response_revision:1614; number_of_response:1; }","duration":"369.101928ms","start":"2026-08-11T11:17:06.53941Z","end":"2026-08-11T11:17:06.908512Z","steps":["trace[1267872293] 'process raft request' (duration: 369.010086ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:17:06.908543Z","caller":"traceutil/trace.go:171","msg":"trace[413407889] linearizableReadLoop","detail":"{readStateIndex:1685; appliedIndex:1685; }","duration":"173.987449ms","start":"2026-08-11T11:17:06.734524Z","end":"2026-08-11T11:17:06.908511Z","steps":["trace[413407889] 'read index received' (duration: 173.974849ms)","trace[413407889] 'applied index is now lower than readState.Index' (duration: 9.94µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:17:06.908657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:06.539355Z","time spent":"369.252222ms","remote":"127.0.0.1:44044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":843,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:17:06.908933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.378659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:17:06.908988Z","caller":"traceutil/trace.go:171","msg":"trace[758853267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1614; }","duration":"174.488482ms","start":"2026-08-11T11:17:06.734485Z","end":"2026-08-11T11:17:06.908973Z","steps":["trace[758853267] 'agreement among raft nodes before linearized reading' (duration: 174.156624ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:17:07.350556Z","caller":"traceutil/trace.go:171","msg":"trace[1818257609] linearizableReadLoop","detail":"{readStateIndex:1687; appliedIndex:1685; }","duration":"259.454924ms","start":"2026-08-11T11:17:07.091082Z","end":"2026-08-11T11:17:07.350537Z","steps":["trace[1818257609] 'read index received' (duration: 55.115585ms)","trace[1818257609] 'applied index is now lower than readState.Index' (duration: 204.338629ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:17:07.350647Z","caller":"traceutil/trace.go:171","msg":"trace[1254507502] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"284.453854ms","start":"2026-08-11T11:17:07.066182Z","end":"2026-08-11T11:17:07.350636Z","steps":["trace[1254507502] 'process raft request' (duration: 213.096707ms)","trace[1254507502] 'compare' (duration: 71.183463ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:17:07.350807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.125442ms","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-08-11T11:17:07.350858Z","caller":"traceutil/trace.go:171","msg":"trace[756222427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1616; }","duration":"237.213585ms","start":"2026-08-11T11:17:07.113632Z","end":"2026-08-11T11:17:07.350846Z","steps":["trace[756222427] 'agreement among raft nodes before linearized reading' (duration: 237.077381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:07.35099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.900985ms","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-08-11T11:17:07.351017Z","caller":"traceutil/trace.go:171","msg":"trace[895678112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1616; }","duration":"259.978897ms","start":"2026-08-11T11:17:07.091031Z","end":"2026-08-11T11:17:07.35101Z","steps":["trace[895678112] 'agreement among raft nodes before linearized reading' (duration: 259.870784ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:17:08.673393Z","caller":"traceutil/trace.go:171","msg":"trace[173782923] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"128.839903ms","start":"2026-08-11T11:17:08.54453Z","end":"2026-08-11T11:17:08.67337Z","steps":["trace[173782923] 'process raft request' (duration: 128.678349ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:17:13.364796Z","caller":"traceutil/trace.go:171","msg":"trace[108080173] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"191.187477ms","start":"2026-08-11T11:17:13.173581Z","end":"2026-08-11T11:17:13.364769Z","steps":["trace[108080173] 'process raft request' (duration: 191.042933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:13.878923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700325608761640398,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:17:14.228873Z","caller":"traceutil/trace.go:171","msg":"trace[1892631491] transaction","detail":"{read_only:false; response_revision:1668; number_of_response:1; }","duration":"851.846785ms","start":"2026-08-11T11:17:13.377Z","end":"2026-08-11T11:17:14.228847Z","steps":["trace[1892631491] 'process raft request' (duration: 808.798764ms)","trace[1892631491] 'compare' (duration: 42.875026ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:17:14.229068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:13.376983Z","time spent":"851.952027ms","remote":"127.0.0.1:44224","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-08-11T11:17:14.565264Z","caller":"traceutil/trace.go:171","msg":"trace[1616056978] linearizableReadLoop","detail":"{readStateIndex:1743; appliedIndex:1739; }","duration":"1.186707551s","start":"2026-08-11T11:17:13.378538Z","end":"2026-08-11T11:17:14.565246Z","steps":["trace[1616056978] 'read index received' (duration: 807.171132ms)","trace[1616056978] 'applied index is now lower than readState.Index' (duration: 379.535709ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:17:14.565303Z","caller":"traceutil/trace.go:171","msg":"trace[1331077160] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"1.032507807s","start":"2026-08-11T11:17:13.532778Z","end":"2026-08-11T11:17:14.565285Z","steps":["trace[1331077160] 'process raft request' (duration: 1.032414285s)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:17:14.565364Z","caller":"traceutil/trace.go:171","msg":"trace[1986963256] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"889.182197ms","start":"2026-08-11T11:17:13.67615Z","end":"2026-08-11T11:17:14.565332Z","steps":["trace[1986963256] 'process raft request' (duration: 889.068874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:14.565403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:13.532754Z","time spent":"1.032584179s","remote":"127.0.0.1:44224","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-08-11T11:17:14.565426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186889746s","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-08-11T11:17:14.56546Z","caller":"traceutil/trace.go:171","msg":"trace[253654809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1671; }","duration":"1.186962268s","start":"2026-08-11T11:17:13.37849Z","end":"2026-08-11T11:17:14.565452Z","steps":["trace[253654809] 'agreement among raft nodes before linearized reading' (duration: 1.186849965s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:14.56555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:13.378472Z","time spent":"1.18707173s","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T11:17:14.565629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:13.676101Z","time spent":"889.33113ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:17:14.565673Z","caller":"traceutil/trace.go:171","msg":"trace[1044273026] transaction","detail":"{read_only:false; response_revision:1669; number_of_response:1; }","duration":"1.188510438s","start":"2026-08-11T11:17:13.377151Z","end":"2026-08-11T11:17:14.565661Z","steps":["trace[1044273026] 'process raft request' (duration: 1.187939253s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:14.565698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.827568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-11T11:17:14.565731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:13.377126Z","time spent":"1.18857598s","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:17:14.565755Z","caller":"traceutil/trace.go:171","msg":"trace[945550206] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1671; }","duration":"913.957361ms","start":"2026-08-11T11:17:13.651785Z","end":"2026-08-11T11:17:14.565742Z","steps":["trace[945550206] 'agreement among raft nodes before linearized reading' (duration: 913.817448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:14.56566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.977366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-08-11T11:17:14.565795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:13.651749Z","time spent":"914.037494ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":11,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-08-11T11:17:14.565806Z","caller":"traceutil/trace.go:171","msg":"trace[1376421308] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1671; }","duration":"157.161281ms","start":"2026-08-11T11:17:14.408638Z","end":"2026-08-11T11:17:14.565799Z","steps":["trace[1376421308] 'agreement among raft nodes before linearized reading' (duration: 156.972106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:14.565943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.769538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:17:14.566041Z","caller":"traceutil/trace.go:171","msg":"trace[2030174113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1671; }","duration":"832.993594ms","start":"2026-08-11T11:17:13.733027Z","end":"2026-08-11T11:17:14.566021Z","steps":["trace[2030174113] 'agreement among raft nodes before linearized reading' (duration: 832.628264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:17:14.566129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:17:13.733008Z","time spent":"833.107247ms","remote":"127.0.0.1:43964","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-08-11T11:19:25.777201Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700325608761641913,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:19:25.928829Z","caller":"traceutil/trace.go:171","msg":"trace[1217820888] linearizableReadLoop","detail":"{readStateIndex:2387; appliedIndex:2386; }","duration":"651.984917ms","start":"2026-08-11T11:19:25.276819Z","end":"2026-08-11T11:19:25.928804Z","steps":["trace[1217820888] 'read index received' (duration: 651.801083ms)","trace[1217820888] 'applied index is now lower than readState.Index' (duration: 183.024µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:19:25.929167Z","caller":"traceutil/trace.go:171","msg":"trace[1832698910] transaction","detail":"{read_only:false; response_revision:2289; number_of_response:1; }","duration":"736.865153ms","start":"2026-08-11T11:19:25.192289Z","end":"2026-08-11T11:19:25.929154Z","steps":["trace[1832698910] 'process raft request' (duration: 736.388121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:25.929299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:25.192273Z","time spent":"736.922945ms","remote":"127.0.0.1:44224","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-08-11T11:19:25.929552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.731716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.83\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-11T11:19:25.929578Z","caller":"traceutil/trace.go:171","msg":"trace[889395453] range","detail":"{range_begin:/registry/masterleases/199.19.213.83; range_end:; response_count:1; response_revision:2289; }","duration":"652.784728ms","start":"2026-08-11T11:19:25.276786Z","end":"2026-08-11T11:19:25.92957Z","steps":["trace[889395453] 'agreement among raft nodes before linearized reading' (duration: 652.664235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:25.9296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:25.276771Z","time spent":"652.823278ms","remote":"127.0.0.1:44004","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.83\" "} {"level":"warn","ts":"2026-08-11T11:19:25.929791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.46609ms","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-08-11T11:19:25.929816Z","caller":"traceutil/trace.go:171","msg":"trace[1713379427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2289; }","duration":"652.510301ms","start":"2026-08-11T11:19:25.277298Z","end":"2026-08-11T11:19:25.929808Z","steps":["trace[1713379427] 'agreement among raft nodes before linearized reading' (duration: 652.429428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:25.929847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:25.277292Z","time spent":"652.543381ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T11:19:25.93018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.134397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:19:25.930201Z","caller":"traceutil/trace.go:171","msg":"trace[531362224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2289; }","duration":"181.183007ms","start":"2026-08-11T11:19:25.749013Z","end":"2026-08-11T11:19:25.930196Z","steps":["trace[531362224] 'agreement among raft nodes before linearized reading' (duration: 181.144716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:25.930384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.248604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-11T11:19:25.930406Z","caller":"traceutil/trace.go:171","msg":"trace[1482538802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2289; }","duration":"411.295235ms","start":"2026-08-11T11:19:25.519103Z","end":"2026-08-11T11:19:25.930398Z","steps":["trace[1482538802] 'agreement among raft nodes before linearized reading' (duration: 411.223413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:25.930429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:25.519092Z","time spent":"411.331436ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T11:19:25.93059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.455799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-11T11:19:25.930606Z","caller":"traceutil/trace.go:171","msg":"trace[1959656326] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2289; }","duration":"411.49815ms","start":"2026-08-11T11:19:25.519103Z","end":"2026-08-11T11:19:25.930601Z","steps":["trace[1959656326] 'agreement among raft nodes before linearized reading' (duration: 411.391118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:25.930622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:25.519092Z","time spent":"411.525841ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-08-11T11:19:40.620619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700325608761642069,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:19:40.625916Z","caller":"traceutil/trace.go:171","msg":"trace[466467076] linearizableReadLoop","detail":"{readStateIndex:2454; appliedIndex:2453; }","duration":"625.671882ms","start":"2026-08-11T11:19:40.000223Z","end":"2026-08-11T11:19:40.625895Z","steps":["trace[466467076] 'read index received' (duration: 625.407915ms)","trace[466467076] 'applied index is now lower than readState.Index' (duration: 263.197µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:19:40.626015Z","caller":"traceutil/trace.go:171","msg":"trace[1040679524] transaction","detail":"{read_only:false; response_revision:2353; number_of_response:1; }","duration":"778.249896ms","start":"2026-08-11T11:19:39.847756Z","end":"2026-08-11T11:19:40.626006Z","steps":["trace[1040679524] 'process raft request' (duration: 777.981069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:40.626161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.599176ms","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:525"} {"level":"info","ts":"2026-08-11T11:19:40.626193Z","caller":"traceutil/trace.go:171","msg":"trace[1209230811] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2353; }","duration":"604.649947ms","start":"2026-08-11T11:19:40.021532Z","end":"2026-08-11T11:19:40.626182Z","steps":["trace[1209230811] 'agreement among raft nodes before linearized reading' (duration: 604.544164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:40.626213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:40.021525Z","time spent":"604.682378ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-11T11:19:40.626359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.142483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T11:19:40.626376Z","caller":"traceutil/trace.go:171","msg":"trace[997581868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2353; }","duration":"626.189125ms","start":"2026-08-11T11:19:40.000181Z","end":"2026-08-11T11:19:40.62637Z","steps":["trace[997581868] 'agreement among raft nodes before linearized reading' (duration: 626.137553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:40.626391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:40.000166Z","time spent":"626.221325ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T11:19:40.626589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.478295ms","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-08-11T11:19:40.626607Z","caller":"traceutil/trace.go:171","msg":"trace[1965802210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2353; }","duration":"568.525986ms","start":"2026-08-11T11:19:40.058075Z","end":"2026-08-11T11:19:40.626601Z","steps":["trace[1965802210] 'agreement among raft nodes before linearized reading' (duration: 568.477215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:40.626621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:40.058061Z","time spent":"568.556508ms","remote":"127.0.0.1:44224","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-08-11T11:19:40.626726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.772032ms","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-08-11T11:19:40.626742Z","caller":"traceutil/trace.go:171","msg":"trace[1904214590] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2353; }","duration":"568.823464ms","start":"2026-08-11T11:19:40.057914Z","end":"2026-08-11T11:19:40.626737Z","steps":["trace[1904214590] 'agreement among raft nodes before linearized reading' (duration: 568.771833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:40.626758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:40.057899Z","time spent":"568.854915ms","remote":"127.0.0.1:44224","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-08-11T11:19:40.62684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.597288ms","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-08-11T11:19:40.626856Z","caller":"traceutil/trace.go:171","msg":"trace[145505010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2353; }","duration":"572.64755ms","start":"2026-08-11T11:19:40.054203Z","end":"2026-08-11T11:19:40.626851Z","steps":["trace[145505010] 'agreement among raft nodes before linearized reading' (duration: 572.603269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:40.626874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:40.054183Z","time spent":"572.68331ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T11:19:40.626945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.429749ms","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-08-11T11:19:40.62696Z","caller":"traceutil/trace.go:171","msg":"trace[589444160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2353; }","duration":"577.445029ms","start":"2026-08-11T11:19:40.04951Z","end":"2026-08-11T11:19:40.626955Z","steps":["trace[589444160] 'agreement among raft nodes before linearized reading' (duration: 577.397588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:40.626975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:40.049493Z","time spent":"577.47761ms","remote":"127.0.0.1:44224","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-08-11T11:19:40.626882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:39.847735Z","time spent":"778.297107ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:19:41.638317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700325608761642080,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:19:41.97275Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.073632791s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T11:19:41.973136Z","caller":"traceutil/trace.go:171","msg":"trace[152550834] transaction","detail":"{read_only:false; response_revision:2360; number_of_response:1; }","duration":"1.074087842s","start":"2026-08-11T11:19:40.899023Z","end":"2026-08-11T11:19:41.973111Z","steps":["trace[152550834] 'process raft request' (duration: 1.073919718s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:41.973297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:40.899002Z","time spent":"1.074194694s","remote":"127.0.0.1:44224","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-08-11T11:19:41.97508Z","caller":"traceutil/trace.go:171","msg":"trace[1485138869] transaction","detail":"{read_only:false; response_revision:2361; number_of_response:1; }","duration":"901.6047ms","start":"2026-08-11T11:19:41.073408Z","end":"2026-08-11T11:19:41.975013Z","steps":["trace[1485138869] 'process raft request' (duration: 901.381105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:41.975164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:41.07339Z","time spent":"901.731374ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:19:41.976521Z","caller":"traceutil/trace.go:171","msg":"trace[517242908] linearizableReadLoop","detail":"{readStateIndex:2463; appliedIndex:2460; }","duration":"838.951058ms","start":"2026-08-11T11:19:41.137552Z","end":"2026-08-11T11:19:41.976503Z","steps":["trace[517242908] 'read index received' (duration: 835.362279ms)","trace[517242908] 'applied index is now lower than readState.Index' (duration: 3.587829ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:19:41.976784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.673781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:19:41.976836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.281056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-11T11:19:41.97687Z","caller":"traceutil/trace.go:171","msg":"trace[893104734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2361; }","duration":"228.859016ms","start":"2026-08-11T11:19:41.747989Z","end":"2026-08-11T11:19:41.976848Z","steps":["trace[893104734] 'agreement among raft nodes before linearized reading' (duration: 228.714062ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:19:41.976884Z","caller":"traceutil/trace.go:171","msg":"trace[581467739] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2361; }","duration":"839.355808ms","start":"2026-08-11T11:19:41.137514Z","end":"2026-08-11T11:19:41.97687Z","steps":["trace[581467739] 'agreement among raft nodes before linearized reading' (duration: 839.200085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:41.976915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:19:41.137496Z","time spent":"839.41259ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-11T11:20:04.48773Z","caller":"traceutil/trace.go:171","msg":"trace[695681272] linearizableReadLoop","detail":"{readStateIndex:2568; appliedIndex:2567; }","duration":"352.735315ms","start":"2026-08-11T11:20:04.134973Z","end":"2026-08-11T11:20:04.487708Z","steps":["trace[695681272] 'read index received' (duration: 352.616422ms)","trace[695681272] 'applied index is now lower than readState.Index' (duration: 117.943µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:20:04.487911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.9279ms","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-08-11T11:20:04.487881Z","caller":"traceutil/trace.go:171","msg":"trace[1018449306] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"409.822489ms","start":"2026-08-11T11:20:04.078028Z","end":"2026-08-11T11:20:04.48785Z","steps":["trace[1018449306] 'process raft request' (duration: 409.549112ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:20:04.487944Z","caller":"traceutil/trace.go:171","msg":"trace[1403697391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2462; }","duration":"353.017742ms","start":"2026-08-11T11:20:04.134918Z","end":"2026-08-11T11:20:04.487936Z","steps":["trace[1403697391] 'agreement among raft nodes before linearized reading' (duration: 352.869348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:20:04.487967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:20:04.134897Z","time spent":"353.065573ms","remote":"127.0.0.1:44224","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-08-11T11:20:04.488062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:20:04.078007Z","time spent":"409.955631ms","remote":"127.0.0.1:44224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}