{"level":"warn","ts":"2026-08-11T15:00:55.434188Z","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-11T15:00:55.434292Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.204.45.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-11T15:00:55.434389Z","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-11T15:00:55.434408Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-08-11T15:00:55.434433Z","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-11T15:00:55.435272Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-08-11T15:00:55.435366Z","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.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-11T15:00:55.438753Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.678091ms"} {"level":"info","ts":"2026-08-11T15:00:55.447996Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-08-11T15:00:55.448151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T15:00:55.44822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-08-11T15:00:55.448237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T15:00:55.448248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-08-11T15:00:55.448304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-08-11T15:00:55.45387Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T15:00:55.456663Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T15:00:55.458098Z","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-11T15:00:55.460396Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T15:00:55.460581Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T15:00:55.46072Z","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-11T15:00:55.460782Z","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-11T15:00:55.460792Z","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-11T15:00:55.461209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-08-11T15:00:55.461292Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-08-11T15:00:55.465613Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-08-11T15:00:55.465656Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-08-11T15:00:55.465695Z","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-11T15:00:55.466122Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T15:00:55.466078Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T15:00:56.348716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T15:00:56.348762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T15:00:56.348792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-08-11T15:00:56.348806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-08-11T15:00:56.348814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-08-11T15:00:56.34883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-08-11T15:00:56.348843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-08-11T15:00:56.350002Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T15:00:56.350845Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T15:00:56.35088Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T15:00:56.351263Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T15:00:56.351326Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T15:00:56.35136Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T15:00:56.351444Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T15:00:56.351471Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T15:00:56.351159Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T15:00:56.354383Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T15:00:56.359809Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-08-11T15:00:57.769949Z","caller":"traceutil/trace.go:171","msg":"trace[537667365] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"195.987092ms","start":"2026-08-11T15:00:57.573942Z","end":"2026-08-11T15:00:57.769929Z","steps":["trace[537667365] 'process raft request' (duration: 195.799528ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:00:58.155203Z","caller":"traceutil/trace.go:171","msg":"trace[1737990326] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"580.845999ms","start":"2026-08-11T15:00:57.574306Z","end":"2026-08-11T15:00:58.155152Z","steps":["trace[1737990326] 'process raft request' (duration: 580.434252ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:00:58.15526Z","caller":"traceutil/trace.go:171","msg":"trace[1110961809] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"510.705908ms","start":"2026-08-11T15:00:57.644487Z","end":"2026-08-11T15:00:58.155193Z","steps":["trace[1110961809] 'process raft request' (duration: 510.579846ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:00:58.155321Z","caller":"traceutil/trace.go:171","msg":"trace[1252287986] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"510.933783ms","start":"2026-08-11T15:00:57.644359Z","end":"2026-08-11T15:00:58.155292Z","steps":["trace[1252287986] 'process raft request' (duration: 510.666857ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:00:58.155314Z","caller":"traceutil/trace.go:171","msg":"trace[1622198918] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"571.117496ms","start":"2026-08-11T15:00:57.584156Z","end":"2026-08-11T15:00:58.155273Z","steps":["trace[1622198918] 'read index received' (duration: 185.612954ms)","trace[1622198918] 'applied index is now lower than readState.Index' (duration: 385.502922ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:00:58.155421Z","caller":"traceutil/trace.go:171","msg":"trace[1730304708] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"510.988164ms","start":"2026-08-11T15:00:57.644417Z","end":"2026-08-11T15:00:58.155405Z","steps":["trace[1730304708] 'process raft request' (duration: 510.630297ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:00:58.155438Z","caller":"traceutil/trace.go:171","msg":"trace[1636838574] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"511.101896ms","start":"2026-08-11T15:00:57.644305Z","end":"2026-08-11T15:00:58.155407Z","steps":["trace[1636838574] 'process raft request' (duration: 510.685718ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:00:58.155451Z","caller":"traceutil/trace.go:171","msg":"trace[190682227] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"505.892238ms","start":"2026-08-11T15:00:57.649522Z","end":"2026-08-11T15:00:58.155414Z","steps":["trace[190682227] 'process raft request' (duration: 505.590752ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:00:58.155526Z","caller":"traceutil/trace.go:171","msg":"trace[1506360926] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"489.552287ms","start":"2026-08-11T15:00:57.665956Z","end":"2026-08-11T15:00:58.155508Z","steps":["trace[1506360926] 'process raft request' (duration: 489.283032ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:00:58.155755Z","caller":"traceutil/trace.go:171","msg":"trace[943959129] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"510.77682ms","start":"2026-08-11T15:00:57.644967Z","end":"2026-08-11T15:00:58.155744Z","steps":["trace[943959129] 'process raft request' (duration: 510.118197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:00:58.15581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.703777ms","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-11T15:00:58.155794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.554926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-11T15:00:58.155879Z","caller":"traceutil/trace.go:171","msg":"trace[1831395395] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"432.781738ms","start":"2026-08-11T15:00:57.723084Z","end":"2026-08-11T15:00:58.155866Z","steps":["trace[1831395395] 'agreement among raft nodes before linearized reading' (duration: 432.672567ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:00:58.155917Z","caller":"traceutil/trace.go:171","msg":"trace[1612285565] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:0; response_revision:11; }","duration":"511.749969ms","start":"2026-08-11T15:00:57.644147Z","end":"2026-08-11T15:00:58.155897Z","steps":["trace[1612285565] 'agreement among raft nodes before linearized reading' (duration: 511.463304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:00:58.155919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.628608ms","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-11T15:00:58.155978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.644111Z","time spent":"511.852451ms","remote":"127.0.0.1:35726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"info","ts":"2026-08-11T15:00:58.156025Z","caller":"traceutil/trace.go:171","msg":"trace[56008541] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"510.76733ms","start":"2026-08-11T15:00:57.645234Z","end":"2026-08-11T15:00:58.156001Z","steps":["trace[56008541] 'agreement among raft nodes before linearized reading' (duration: 510.565336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:00:58.15607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.645225Z","time spent":"510.834611ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-11T15:00:58.156122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.574288Z","time spent":"581.030254ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T15:00:58.155928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.016905ms","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-11T15:00:58.156249Z","caller":"traceutil/trace.go:171","msg":"trace[637470363] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"511.380201ms","start":"2026-08-11T15:00:57.644847Z","end":"2026-08-11T15:00:58.156227Z","steps":["trace[637470363] 'agreement among raft nodes before linearized reading' (duration: 510.997034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:00:58.156294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.644453Z","time spent":"510.874993ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T15:00:58.156295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.644836Z","time spent":"511.443743ms","remote":"127.0.0.1:35778","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-11T15:00:58.156413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.644348Z","time spent":"511.037625ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T15:00:58.156493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.644392Z","time spent":"511.085556ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T15:00:58.156297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.398519ms","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-11T15:00:58.156525Z","caller":"traceutil/trace.go:171","msg":"trace[196151212] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"454.687984ms","start":"2026-08-11T15:00:57.701824Z","end":"2026-08-11T15:00:58.156512Z","steps":["trace[196151212] 'agreement among raft nodes before linearized reading' (duration: 454.43971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:00:58.15656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.7018Z","time spent":"454.750885ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-11T15:00:58.156696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.644281Z","time spent":"511.204009ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T15:00:58.156826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.649501Z","time spent":"506.02727ms","remote":"127.0.0.1:33776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T15:00:58.157084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.80309ms","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-11T15:00:58.157114Z","caller":"traceutil/trace.go:171","msg":"trace[1223804477] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"432.8607ms","start":"2026-08-11T15:00:57.724245Z","end":"2026-08-11T15:00:58.157105Z","steps":["trace[1223804477] 'agreement among raft nodes before linearized reading' (duration: 432.815019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:00:58.157145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.724237Z","time spent":"432.900511ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-11T15:00:58.157276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.121963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-11T15:00:58.157303Z","caller":"traceutil/trace.go:171","msg":"trace[626079477] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"573.163305ms","start":"2026-08-11T15:00:57.584132Z","end":"2026-08-11T15:00:58.157295Z","steps":["trace[626079477] 'agreement among raft nodes before linearized reading' (duration: 573.124284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:00:58.157326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.584125Z","time spent":"573.196585ms","remote":"127.0.0.1:33724","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-11T15:00:58.157586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.033183ms","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-11T15:00:58.157613Z","caller":"traceutil/trace.go:171","msg":"trace[1504976531] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"434.115944ms","start":"2026-08-11T15:00:57.723489Z","end":"2026-08-11T15:00:58.157605Z","steps":["trace[1504976531] 'agreement among raft nodes before linearized reading' (duration: 434.074513ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:00:58.15764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.723462Z","time spent":"434.172485ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-11T15:00:58.158319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.644957Z","time spent":"510.844751ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T15:00:58.158449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.723043Z","time spent":"435.380828ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-11T15:00:58.158885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.665941Z","time spent":"489.625889ms","remote":"127.0.0.1:35930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T15:00:58.155558Z","caller":"traceutil/trace.go:171","msg":"trace[475470962] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"494.117443ms","start":"2026-08-11T15:00:57.661404Z","end":"2026-08-11T15:00:58.155521Z","steps":["trace[475470962] 'process raft request' (duration: 493.787666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:00:58.160476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:00:57.661385Z","time spent":"499.048967ms","remote":"127.0.0.1:35866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T15:01:16.528064Z","caller":"traceutil/trace.go:171","msg":"trace[1586147367] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"117.593984ms","start":"2026-08-11T15:01:16.410425Z","end":"2026-08-11T15:01:16.528019Z","steps":["trace[1586147367] 'process raft request' (duration: 117.439851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:33.182577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.524396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T15:01:33.18306Z","caller":"traceutil/trace.go:171","msg":"trace[1110425068] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"591.443261ms","start":"2026-08-11T15:01:32.591598Z","end":"2026-08-11T15:01:33.183041Z","steps":["trace[1110425068] 'read index received' (duration: 299.986397ms)","trace[1110425068] 'applied index is now lower than readState.Index' (duration: 291.455854ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:01:33.183069Z","caller":"traceutil/trace.go:171","msg":"trace[505019170] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"622.771367ms","start":"2026-08-11T15:01:32.56021Z","end":"2026-08-11T15:01:33.182981Z","steps":["trace[505019170] 'process raft request' (duration: 331.600348ms)","trace[505019170] 'compare' (duration: 290.368124ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T15:01:33.183296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.672005ms","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-11T15:01:33.183417Z","caller":"traceutil/trace.go:171","msg":"trace[1075316530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"591.819279ms","start":"2026-08-11T15:01:32.591574Z","end":"2026-08-11T15:01:33.183393Z","steps":["trace[1075316530] 'agreement among raft nodes before linearized reading' (duration: 591.632775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:33.183479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:32.591561Z","time spent":"591.90607ms","remote":"127.0.0.1:35942","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-11T15:01:33.18358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:32.560195Z","time spent":"623.111893ms","remote":"127.0.0.1:35732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T15:01:33.183663Z","caller":"traceutil/trace.go:171","msg":"trace[551539805] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"611.793208ms","start":"2026-08-11T15:01:32.571852Z","end":"2026-08-11T15:01:33.183645Z","steps":["trace[551539805] 'process raft request' (duration: 610.910192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:33.183799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:32.57183Z","time spent":"611.88387ms","remote":"127.0.0.1:35732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T15:01:33.183874Z","caller":"traceutil/trace.go:171","msg":"trace[1362973145] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"568.085969ms","start":"2026-08-11T15:01:32.615753Z","end":"2026-08-11T15:01:33.183839Z","steps":["trace[1362973145] 'process raft request' (duration: 567.223552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:33.183937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:32.615709Z","time spent":"568.203301ms","remote":"127.0.0.1:35732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T15:01:33.184182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.86289ms","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-11T15:01:33.185419Z","caller":"traceutil/trace.go:171","msg":"trace[498723641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"185.570674ms","start":"2026-08-11T15:01:32.998964Z","end":"2026-08-11T15:01:33.184535Z","steps":["trace[498723641] 'agreement among raft nodes before linearized reading' (duration: 184.389341ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:01:36.610187Z","caller":"traceutil/trace.go:171","msg":"trace[1434749490] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"110.223413ms","start":"2026-08-11T15:01:36.499924Z","end":"2026-08-11T15:01:36.610147Z","steps":["trace[1434749490] 'process raft request' (duration: 34.224869ms)","trace[1434749490] 'compare' (duration: 75.810371ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:01:39.561663Z","caller":"traceutil/trace.go:171","msg":"trace[2077261728] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"366.848157ms","start":"2026-08-11T15:01:39.194765Z","end":"2026-08-11T15:01:39.561613Z","steps":["trace[2077261728] 'read index received' (duration: 358.802124ms)","trace[2077261728] 'applied index is now lower than readState.Index' (duration: 8.045193ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:01:39.56173Z","caller":"traceutil/trace.go:171","msg":"trace[79806523] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"564.041672ms","start":"2026-08-11T15:01:38.997646Z","end":"2026-08-11T15:01:39.561687Z","steps":["trace[79806523] 'process raft request' (duration: 557.11612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:39.56191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:38.997603Z","time spent":"564.213895ms","remote":"127.0.0.1:35794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T15:01:39.561923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.137752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-74bgc\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-08-11T15:01:39.561969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.096489ms","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-11T15:01:39.562014Z","caller":"traceutil/trace.go:171","msg":"trace[1985238638] range","detail":"{range_begin:/registry/pods/kube-system/cilium-74bgc; range_end:; response_count:1; response_revision:627; }","duration":"367.258344ms","start":"2026-08-11T15:01:39.19473Z","end":"2026-08-11T15:01:39.561988Z","steps":["trace[1985238638] 'agreement among raft nodes before linearized reading' (duration: 367.03531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:39.562004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.948074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-11T15:01:39.562064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:39.194713Z","time spent":"367.343416ms","remote":"127.0.0.1:35882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-74bgc\" "} {"level":"info","ts":"2026-08-11T15:01:39.562067Z","caller":"traceutil/trace.go:171","msg":"trace[1864650079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"339.229612ms","start":"2026-08-11T15:01:39.222815Z","end":"2026-08-11T15:01:39.562045Z","steps":["trace[1864650079] 'agreement among raft nodes before linearized reading' (duration: 339.017478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:39.562116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:39.222795Z","time spent":"339.311443ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T15:01:39.562111Z","caller":"traceutil/trace.go:171","msg":"trace[1796517225] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:627; }","duration":"293.151077ms","start":"2026-08-11T15:01:39.268931Z","end":"2026-08-11T15:01:39.562082Z","steps":["trace[1796517225] 'agreement among raft nodes before linearized reading' (duration: 292.927833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:39.561956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.245537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T15:01:39.562196Z","caller":"traceutil/trace.go:171","msg":"trace[1513208461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"118.561243ms","start":"2026-08-11T15:01:39.443621Z","end":"2026-08-11T15:01:39.562182Z","steps":["trace[1513208461] 'agreement among raft nodes before linearized reading' (duration: 118.254317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:39.562222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.645333ms","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-11T15:01:39.56234Z","caller":"traceutil/trace.go:171","msg":"trace[617991145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"337.843906ms","start":"2026-08-11T15:01:39.224465Z","end":"2026-08-11T15:01:39.562309Z","steps":["trace[617991145] 'agreement among raft nodes before linearized reading' (duration: 337.478039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:39.562393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:39.224429Z","time spent":"337.953597ms","remote":"127.0.0.1:35942","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-11T15:01:40.165309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054644488150365,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T15:01:40.400958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.659519ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-08-11T15:01:40.401181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:39.563093Z","time spent":"838.075435ms","remote":"127.0.0.1:35726","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-11T15:01:40.401383Z","caller":"traceutil/trace.go:171","msg":"trace[607305966] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:650; }","duration":"736.553758ms","start":"2026-08-11T15:01:39.664812Z","end":"2026-08-11T15:01:40.401366Z","steps":["trace[607305966] 'read index received' (duration: 715.381446ms)","trace[607305966] 'applied index is now lower than readState.Index' (duration: 21.171392ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:01:40.401613Z","caller":"traceutil/trace.go:171","msg":"trace[1573018268] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"779.127977ms","start":"2026-08-11T15:01:39.622464Z","end":"2026-08-11T15:01:40.401592Z","steps":["trace[1573018268] 'process raft request' (duration: 778.866101ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:01:40.401657Z","caller":"traceutil/trace.go:171","msg":"trace[832175753] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"816.032037ms","start":"2026-08-11T15:01:39.585614Z","end":"2026-08-11T15:01:40.401646Z","steps":["trace[832175753] 'process raft request' (duration: 815.670599ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:01:40.40162Z","caller":"traceutil/trace.go:171","msg":"trace[1463763378] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"833.721464ms","start":"2026-08-11T15:01:39.567865Z","end":"2026-08-11T15:01:40.401586Z","steps":["trace[1463763378] 'process raft request' (duration: 833.355156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:40.401709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:39.622443Z","time spent":"779.209488ms","remote":"127.0.0.1:33830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T15:01:40.401732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:39.585581Z","time spent":"816.103149ms","remote":"127.0.0.1:35882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T15:01:40.401826Z","caller":"traceutil/trace.go:171","msg":"trace[278867744] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"835.518818ms","start":"2026-08-11T15:01:39.566286Z","end":"2026-08-11T15:01:40.401804Z","steps":["trace[278867744] 'process raft request' (duration: 834.801974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:40.401833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:39.567845Z","time spent":"833.864636ms","remote":"127.0.0.1:35942","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-11T15:01:40.401858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.053208ms","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-11T15:01:40.401889Z","caller":"traceutil/trace.go:171","msg":"trace[278385605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"737.120039ms","start":"2026-08-11T15:01:39.664761Z","end":"2026-08-11T15:01:40.401881Z","steps":["trace[278385605] 'agreement among raft nodes before linearized reading' (duration: 737.006407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:40.401916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:39.664703Z","time spent":"737.206461ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T15:01:40.401736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.221665ms","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-11T15:01:40.402039Z","caller":"traceutil/trace.go:171","msg":"trace[823135302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"535.556091ms","start":"2026-08-11T15:01:39.866456Z","end":"2026-08-11T15:01:40.402012Z","steps":["trace[823135302] 'agreement among raft nodes before linearized reading' (duration: 535.115193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:40.402106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:39.866437Z","time spent":"535.641603ms","remote":"127.0.0.1:35942","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-11T15:01:40.401934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:39.565647Z","time spent":"836.22239ms","remote":"127.0.0.1:35942","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-11T15:01:41.743999Z","caller":"traceutil/trace.go:171","msg":"trace[1301082458] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"885.112958ms","start":"2026-08-11T15:01:40.858864Z","end":"2026-08-11T15:01:41.743977Z","steps":["trace[1301082458] 'process raft request' (duration: 884.399045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:01:41.744179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:01:40.858844Z","time spent":"885.243861ms","remote":"127.0.0.1:35794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T15:01:43.540181Z","caller":"traceutil/trace.go:171","msg":"trace[2044299460] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"196.210366ms","start":"2026-08-11T15:01:43.343931Z","end":"2026-08-11T15:01:43.540141Z","steps":["trace[2044299460] 'process raft request' (duration: 195.937101ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:01:57.2964Z","caller":"traceutil/trace.go:171","msg":"trace[869105582] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"275.707685ms","start":"2026-08-11T15:01:57.020659Z","end":"2026-08-11T15:01:57.296366Z","steps":["trace[869105582] 'process raft request' (duration: 275.45782ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:02:12.096344Z","caller":"traceutil/trace.go:171","msg":"trace[959296593] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"340.230659ms","start":"2026-08-11T15:02:11.756094Z","end":"2026-08-11T15:02:12.096325Z","steps":["trace[959296593] 'process raft request' (duration: 340.090505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:12.096497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:02:11.756069Z","time spent":"340.362952ms","remote":"127.0.0.1:35882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T15:02:12.112513Z","caller":"traceutil/trace.go:171","msg":"trace[1685900236] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"298.705268ms","start":"2026-08-11T15:02:11.813783Z","end":"2026-08-11T15:02:12.112488Z","steps":["trace[1685900236] 'process raft request' (duration: 298.595416ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:02:12.413671Z","caller":"traceutil/trace.go:171","msg":"trace[904216534] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"122.613863ms","start":"2026-08-11T15:02:12.291035Z","end":"2026-08-11T15:02:12.413649Z","steps":["trace[904216534] 'process raft request' (duration: 66.913999ms)","trace[904216534] 'compare' (duration: 55.601002ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:02:22.951385Z","caller":"traceutil/trace.go:171","msg":"trace[1962834104] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"286.584614ms","start":"2026-08-11T15:02:22.664774Z","end":"2026-08-11T15:02:22.951358Z","steps":["trace[1962834104] 'read index received' (duration: 286.39982ms)","trace[1962834104] 'applied index is now lower than readState.Index' (duration: 183.594µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:02:22.951496Z","caller":"traceutil/trace.go:171","msg":"trace[312655624] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"449.287891ms","start":"2026-08-11T15:02:22.502182Z","end":"2026-08-11T15:02:22.95147Z","steps":["trace[312655624] 'process raft request' (duration: 448.946864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:22.951587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.80044ms","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-11T15:02:22.951624Z","caller":"traceutil/trace.go:171","msg":"trace[500965164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"286.87371ms","start":"2026-08-11T15:02:22.66474Z","end":"2026-08-11T15:02:22.951614Z","steps":["trace[500965164] 'agreement among raft nodes before linearized reading' (duration: 286.746517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:22.951631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:02:22.502159Z","time spent":"449.392253ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T15:02:23.451945Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054644488151060,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T15:02:23.563086Z","caller":"traceutil/trace.go:171","msg":"trace[1535638795] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"835.757273ms","start":"2026-08-11T15:02:22.72731Z","end":"2026-08-11T15:02:23.563067Z","steps":["trace[1535638795] 'process raft request' (duration: 832.50958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:23.56319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:02:22.727276Z","time spent":"835.862484ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T15:02:23.566438Z","caller":"traceutil/trace.go:171","msg":"trace[1127706842] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1010; }","duration":"614.970327ms","start":"2026-08-11T15:02:22.951456Z","end":"2026-08-11T15:02:23.566426Z","steps":["trace[1127706842] 'read index received' (duration: 608.37511ms)","trace[1127706842] 'applied index is now lower than readState.Index' (duration: 6.594657ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:02:23.56655Z","caller":"traceutil/trace.go:171","msg":"trace[943065288] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"611.445338ms","start":"2026-08-11T15:02:22.95508Z","end":"2026-08-11T15:02:23.566525Z","steps":["trace[943065288] 'process raft request' (duration: 611.280195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:23.566587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.146046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-11T15:02:23.566596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.600817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-11T15:02:23.566625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:02:22.955062Z","time spent":"611.52299ms","remote":"127.0.0.1:35942","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-11T15:02:23.566635Z","caller":"traceutil/trace.go:171","msg":"trace[1132414340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:977; }","duration":"895.667848ms","start":"2026-08-11T15:02:22.670955Z","end":"2026-08-11T15:02:23.566623Z","steps":["trace[1132414340] 'agreement among raft nodes before linearized reading' (duration: 895.540085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:23.566654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.205124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-11T15:02:23.566662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:02:22.670944Z","time spent":"895.712308ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T15:02:23.566674Z","caller":"traceutil/trace.go:171","msg":"trace[500190888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:977; }","duration":"901.263345ms","start":"2026-08-11T15:02:22.665406Z","end":"2026-08-11T15:02:23.566669Z","steps":["trace[500190888] 'agreement among raft nodes before linearized reading' (duration: 901.212044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:23.566689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:02:22.665385Z","time spent":"901.300376ms","remote":"127.0.0.1:35942","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-11T15:02:23.566608Z","caller":"traceutil/trace.go:171","msg":"trace[1532282367] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:977; }","duration":"897.216257ms","start":"2026-08-11T15:02:22.669386Z","end":"2026-08-11T15:02:23.566603Z","steps":["trace[1532282367] 'agreement among raft nodes before linearized reading' (duration: 897.106855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:23.566754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.993899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-11T15:02:23.566752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:02:22.669371Z","time spent":"897.37734ms","remote":"127.0.0.1:35942","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-11T15:02:23.566765Z","caller":"traceutil/trace.go:171","msg":"trace[1604461485] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:977; }","duration":"486.03882ms","start":"2026-08-11T15:02:23.080723Z","end":"2026-08-11T15:02:23.566761Z","steps":["trace[1604461485] 'agreement among raft nodes before linearized reading' (duration: 486.01049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:23.566777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:02:23.080698Z","time spent":"486.07598ms","remote":"127.0.0.1:36004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-11T15:02:23.566952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.594179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-11T15:02:23.566987Z","caller":"traceutil/trace.go:171","msg":"trace[2047343587] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:977; }","duration":"155.662241ms","start":"2026-08-11T15:02:23.411316Z","end":"2026-08-11T15:02:23.566978Z","steps":["trace[2047343587] 'agreement among raft nodes before linearized reading' (duration: 155.540759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:23.953729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.11225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T15:02:23.953916Z","caller":"traceutil/trace.go:171","msg":"trace[838713879] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1013; }","duration":"161.671246ms","start":"2026-08-11T15:02:23.792229Z","end":"2026-08-11T15:02:23.9539Z","steps":["trace[838713879] 'read index received' (duration: 59.911µs)","trace[838713879] 'applied index is now lower than readState.Index' (duration: 161.610365ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:02:23.953918Z","caller":"traceutil/trace.go:171","msg":"trace[978216823] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"383.985872ms","start":"2026-08-11T15:02:23.569906Z","end":"2026-08-11T15:02:23.953892Z","steps":["trace[978216823] 'process raft request' (duration: 117.639417ms)","trace[978216823] 'compare' (duration: 265.993568ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T15:02:23.953996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.762728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T15:02:23.954027Z","caller":"traceutil/trace.go:171","msg":"trace[815321664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"161.85586ms","start":"2026-08-11T15:02:23.792161Z","end":"2026-08-11T15:02:23.954017Z","steps":["trace[815321664] 'agreement among raft nodes before linearized reading' (duration: 161.785668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:23.954087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:02:23.569886Z","time spent":"384.104675ms","remote":"127.0.0.1:35942","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-11T15:02:23.954141Z","caller":"traceutil/trace.go:171","msg":"trace[1083878007] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"384.131056ms","start":"2026-08-11T15:02:23.569996Z","end":"2026-08-11T15:02:23.954127Z","steps":["trace[1083878007] 'process raft request' (duration: 383.82157ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:02:23.954177Z","caller":"traceutil/trace.go:171","msg":"trace[793331556] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"381.354902ms","start":"2026-08-11T15:02:23.572807Z","end":"2026-08-11T15:02:23.954162Z","steps":["trace[793331556] 'process raft request' (duration: 381.058485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:23.954202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:02:23.569972Z","time spent":"384.194077ms","remote":"127.0.0.1:35942","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-11T15:02:23.954229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:02:23.572795Z","time spent":"381.406774ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T15:02:25.137774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.257767ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-11T15:02:25.31544Z","caller":"traceutil/trace.go:171","msg":"trace[1264105315] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"359.470891ms","start":"2026-08-11T15:02:24.95594Z","end":"2026-08-11T15:02:25.315411Z","steps":["trace[1264105315] 'process raft request' (duration: 359.204476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:25.315625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:02:24.9559Z","time spent":"359.622193ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T15:02:43.896891Z","caller":"traceutil/trace.go:171","msg":"trace[2005743817] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"181.189792ms","start":"2026-08-11T15:02:43.715683Z","end":"2026-08-11T15:02:43.896872Z","steps":["trace[2005743817] 'read index received' (duration: 180.985868ms)","trace[2005743817] 'applied index is now lower than readState.Index' (duration: 203.314µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:02:43.896924Z","caller":"traceutil/trace.go:171","msg":"trace[1176058133] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"201.901172ms","start":"2026-08-11T15:02:43.695007Z","end":"2026-08-11T15:02:43.896909Z","steps":["trace[1176058133] 'process raft request' (duration: 201.739569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:43.897088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.390917ms","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-11T15:02:43.897119Z","caller":"traceutil/trace.go:171","msg":"trace[1135119397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1046; }","duration":"181.461328ms","start":"2026-08-11T15:02:43.715651Z","end":"2026-08-11T15:02:43.897112Z","steps":["trace[1135119397] 'agreement among raft nodes before linearized reading' (duration: 181.343345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:02:43.897366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.572587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T15:02:43.897478Z","caller":"traceutil/trace.go:171","msg":"trace[1355562846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"102.819822ms","start":"2026-08-11T15:02:43.794624Z","end":"2026-08-11T15:02:43.897444Z","steps":["trace[1355562846] 'agreement among raft nodes before linearized reading' (duration: 102.493926ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:03:16.413648Z","caller":"traceutil/trace.go:171","msg":"trace[2141212671] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"113.021198ms","start":"2026-08-11T15:03:16.300558Z","end":"2026-08-11T15:03:16.413579Z","steps":["trace[2141212671] 'process raft request' (duration: 81.286844ms)","trace[2141212671] 'compare' (duration: 31.567051ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:03:18.241082Z","caller":"traceutil/trace.go:171","msg":"trace[572275728] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"190.028328ms","start":"2026-08-11T15:03:18.051025Z","end":"2026-08-11T15:03:18.241054Z","steps":["trace[572275728] 'process raft request' (duration: 189.876435ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:03:18.80065Z","caller":"traceutil/trace.go:171","msg":"trace[716488201] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1277; }","duration":"566.775862ms","start":"2026-08-11T15:03:18.23384Z","end":"2026-08-11T15:03:18.800616Z","steps":["trace[716488201] 'read index received' (duration: 7.026996ms)","trace[716488201] 'applied index is now lower than readState.Index' (duration: 559.747886ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:03:18.800739Z","caller":"traceutil/trace.go:171","msg":"trace[1019978357] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"747.209016ms","start":"2026-08-11T15:03:18.053487Z","end":"2026-08-11T15:03:18.800696Z","steps":["trace[1019978357] 'process raft request' (duration: 650.510224ms)","trace[1019978357] 'compare' (duration: 96.502828ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T15:03:18.801009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.118829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-11T15:03:18.801049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:18.053469Z","time spent":"747.42483ms","remote":"127.0.0.1:35942","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-11T15:03:18.801151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.954691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T15:03:18.801176Z","caller":"traceutil/trace.go:171","msg":"trace[1479240178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1230; }","duration":"355.005382ms","start":"2026-08-11T15:03:18.446165Z","end":"2026-08-11T15:03:18.80117Z","steps":["trace[1479240178] 'agreement among raft nodes before linearized reading' (duration: 354.965892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:18.801202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:18.446148Z","time spent":"355.048923ms","remote":"127.0.0.1:35694","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-11T15:03:18.801204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.825171ms","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-11T15:03:18.801256Z","caller":"traceutil/trace.go:171","msg":"trace[1718689899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1230; }","duration":"429.918243ms","start":"2026-08-11T15:03:18.371324Z","end":"2026-08-11T15:03:18.801243Z","steps":["trace[1718689899] 'agreement among raft nodes before linearized reading' (duration: 429.74677ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:03:18.801104Z","caller":"traceutil/trace.go:171","msg":"trace[2047215338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"567.293923ms","start":"2026-08-11T15:03:18.233785Z","end":"2026-08-11T15:03:18.801079Z","steps":["trace[2047215338] 'agreement among raft nodes before linearized reading' (duration: 567.040268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:18.801303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:18.371303Z","time spent":"429.990404ms","remote":"127.0.0.1:35942","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-11T15:03:18.801329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:18.233757Z","time spent":"567.552517ms","remote":"127.0.0.1:35942","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-11T15:03:18.801364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.067325ms","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-11T15:03:18.801479Z","caller":"traceutil/trace.go:171","msg":"trace[2085384246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1230; }","duration":"383.320921ms","start":"2026-08-11T15:03:18.41814Z","end":"2026-08-11T15:03:18.801461Z","steps":["trace[2085384246] 'agreement among raft nodes before linearized reading' (duration: 382.79499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:18.801548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:18.418119Z","time spent":"383.414382ms","remote":"127.0.0.1:35942","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-11T15:03:18.801036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.709184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T15:03:18.805005Z","caller":"traceutil/trace.go:171","msg":"trace[1509494499] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1230; }","duration":"535.462777ms","start":"2026-08-11T15:03:18.269251Z","end":"2026-08-11T15:03:18.804713Z","steps":["trace[1509494499] 'agreement among raft nodes before linearized reading' (duration: 531.668234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:18.805209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:18.269228Z","time spent":"535.952066ms","remote":"127.0.0.1:60524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-11T15:03:20.227666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.272757ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-11T15:03:20.227755Z","caller":"traceutil/trace.go:171","msg":"trace[1420527628] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"871.826617ms","start":"2026-08-11T15:03:19.355913Z","end":"2026-08-11T15:03:20.22774Z","steps":["trace[1420527628] 'read index received' (duration: 327.737175ms)","trace[1420527628] 'applied index is now lower than readState.Index' (duration: 544.087962ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T15:03:20.227805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:19.336301Z","time spent":"891.501438ms","remote":"127.0.0.1:35726","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-11T15:03:20.22799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.056181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T15:03:20.22808Z","caller":"traceutil/trace.go:171","msg":"trace[74098493] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1235; }","duration":"872.155543ms","start":"2026-08-11T15:03:19.3559Z","end":"2026-08-11T15:03:20.228056Z","steps":["trace[74098493] 'agreement among raft nodes before linearized reading' (duration: 872.00764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:20.228117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:19.355846Z","time spent":"872.265056ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-11T15:03:20.230378Z","caller":"traceutil/trace.go:171","msg":"trace[1926788683] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"653.515352ms","start":"2026-08-11T15:03:19.576846Z","end":"2026-08-11T15:03:20.230362Z","steps":["trace[1926788683] 'process raft request' (duration: 653.364729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:20.230504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:19.576818Z","time spent":"653.603734ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T15:03:20.308564Z","caller":"traceutil/trace.go:171","msg":"trace[423136097] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"481.346759ms","start":"2026-08-11T15:03:19.827189Z","end":"2026-08-11T15:03:20.308536Z","steps":["trace[423136097] 'process raft request' (duration: 481.155445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:20.308769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:19.827156Z","time spent":"481.494922ms","remote":"127.0.0.1:35762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T15:03:20.309008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.480008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T15:03:20.309044Z","caller":"traceutil/trace.go:171","msg":"trace[1794424255] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1238; }","duration":"767.666741ms","start":"2026-08-11T15:03:19.541367Z","end":"2026-08-11T15:03:20.309033Z","steps":["trace[1794424255] 'agreement among raft nodes before linearized reading' (duration: 767.526598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:20.309067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:19.541334Z","time spent":"767.727513ms","remote":"127.0.0.1:33792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-08-11T15:03:20.309229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.538918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T15:03:20.309328Z","caller":"traceutil/trace.go:171","msg":"trace[1100447133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1238; }","duration":"862.726922ms","start":"2026-08-11T15:03:19.446578Z","end":"2026-08-11T15:03:20.309305Z","steps":["trace[1100447133] 'agreement among raft nodes before linearized reading' (duration: 862.494138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:20.309386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:19.446557Z","time spent":"862.818953ms","remote":"127.0.0.1:35694","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-11T15:03:20.461628Z","caller":"traceutil/trace.go:171","msg":"trace[543118545] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1288; }","duration":"153.072623ms","start":"2026-08-11T15:03:20.308505Z","end":"2026-08-11T15:03:20.461578Z","steps":["trace[543118545] 'read index received' (duration: 66.481997ms)","trace[543118545] 'applied index is now lower than readState.Index' (duration: 86.586666ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:03:20.461719Z","caller":"traceutil/trace.go:171","msg":"trace[982557643] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"225.250581ms","start":"2026-08-11T15:03:20.236386Z","end":"2026-08-11T15:03:20.461637Z","steps":["trace[982557643] 'process raft request' (duration: 138.587213ms)","trace[982557643] 'compare' (duration: 86.23524ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T15:03:20.462055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.378019ms","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-11T15:03:20.462178Z","caller":"traceutil/trace.go:171","msg":"trace[378475757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"216.560403ms","start":"2026-08-11T15:03:20.245593Z","end":"2026-08-11T15:03:20.462154Z","steps":["trace[378475757] 'agreement among raft nodes before linearized reading' (duration: 216.161044ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:03:20.495753Z","caller":"traceutil/trace.go:171","msg":"trace[324186856] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"182.114206ms","start":"2026-08-11T15:03:20.313605Z","end":"2026-08-11T15:03:20.495719Z","steps":["trace[324186856] 'process raft request' (duration: 181.956152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:20.495971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.130495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-11T15:03:20.496093Z","caller":"traceutil/trace.go:171","msg":"trace[1320683562] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1240; }","duration":"184.41343ms","start":"2026-08-11T15:03:20.311659Z","end":"2026-08-11T15:03:20.496072Z","steps":["trace[1320683562] 'agreement among raft nodes before linearized reading' (duration: 184.075984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:20.496284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.881939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T15:03:20.496337Z","caller":"traceutil/trace.go:171","msg":"trace[1823120887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"185.004531ms","start":"2026-08-11T15:03:20.311319Z","end":"2026-08-11T15:03:20.496323Z","steps":["trace[1823120887] 'agreement among raft nodes before linearized reading' (duration: 184.898759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:21.344939Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054644488151700,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T15:03:21.499726Z","caller":"traceutil/trace.go:171","msg":"trace[1337878946] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"681.061085ms","start":"2026-08-11T15:03:20.818634Z","end":"2026-08-11T15:03:21.499695Z","steps":["trace[1337878946] 'process raft request' (duration: 680.507414ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:03:21.499677Z","caller":"traceutil/trace.go:171","msg":"trace[555296898] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"654.97174ms","start":"2026-08-11T15:03:20.844671Z","end":"2026-08-11T15:03:21.499643Z","steps":["trace[555296898] 'read index received' (duration: 654.442519ms)","trace[555296898] 'applied index is now lower than readState.Index' (duration: 526.991µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T15:03:21.499894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.210834ms","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-11T15:03:21.500014Z","caller":"traceutil/trace.go:171","msg":"trace[2081630742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1245; }","duration":"655.377318ms","start":"2026-08-11T15:03:20.844617Z","end":"2026-08-11T15:03:21.499994Z","steps":["trace[2081630742] 'agreement among raft nodes before linearized reading' (duration: 655.104912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:21.500018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:20.818603Z","time spent":"681.210298ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T15:03:21.500058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:20.844594Z","time spent":"655.451809ms","remote":"127.0.0.1:35942","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-11T15:03:21.500143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.217955ms","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-11T15:03:21.500222Z","caller":"traceutil/trace.go:171","msg":"trace[1831002749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1245; }","duration":"623.336817ms","start":"2026-08-11T15:03:20.87684Z","end":"2026-08-11T15:03:21.500177Z","steps":["trace[1831002749] 'agreement among raft nodes before linearized reading' (duration: 623.136163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:21.500254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.38314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T15:03:21.500322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.437919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-11T15:03:21.500267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:20.876821Z","time spent":"623.434229ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T15:03:21.500372Z","caller":"traceutil/trace.go:171","msg":"trace[257112292] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1245; }","duration":"199.518652ms","start":"2026-08-11T15:03:21.300825Z","end":"2026-08-11T15:03:21.500344Z","steps":["trace[257112292] 'agreement among raft nodes before linearized reading' (duration: 199.334269ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:03:21.500398Z","caller":"traceutil/trace.go:171","msg":"trace[247530579] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1245; }","duration":"622.535293ms","start":"2026-08-11T15:03:20.87783Z","end":"2026-08-11T15:03:21.500366Z","steps":["trace[247530579] 'agreement among raft nodes before linearized reading' (duration: 622.366539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:21.500446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:20.87782Z","time spent":"622.613544ms","remote":"127.0.0.1:35942","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-11T15:03:21.630363Z","caller":"traceutil/trace.go:171","msg":"trace[1203288291] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"123.496401ms","start":"2026-08-11T15:03:21.506834Z","end":"2026-08-11T15:03:21.630331Z","steps":["trace[1203288291] 'process raft request' (duration: 123.44051ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:03:21.630464Z","caller":"traceutil/trace.go:171","msg":"trace[1462260097] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"123.659263ms","start":"2026-08-11T15:03:21.50675Z","end":"2026-08-11T15:03:21.630409Z","steps":["trace[1462260097] 'process raft request' (duration: 85.26559ms)","trace[1462260097] 'compare' (duration: 38.102528ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:03:27.189162Z","caller":"traceutil/trace.go:171","msg":"trace[1807613916] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"306.893451ms","start":"2026-08-11T15:03:26.88223Z","end":"2026-08-11T15:03:27.189123Z","steps":["trace[1807613916] 'process raft request' (duration: 306.633227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:27.189396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:26.882211Z","time spent":"307.067105ms","remote":"127.0.0.1:35882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13706,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T15:03:27.931947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656054644488151785,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T15:03:28.171638Z","caller":"traceutil/trace.go:171","msg":"trace[925693591] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1345; }","duration":"740.392964ms","start":"2026-08-11T15:03:27.431216Z","end":"2026-08-11T15:03:28.171608Z","steps":["trace[925693591] 'read index received' (duration: 740.234431ms)","trace[925693591] 'applied index is now lower than readState.Index' (duration: 157.133µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:03:28.171788Z","caller":"traceutil/trace.go:171","msg":"trace[1296065844] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"971.005428ms","start":"2026-08-11T15:03:27.20077Z","end":"2026-08-11T15:03:28.171776Z","steps":["trace[1296065844] 'process raft request' (duration: 970.772294ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:03:28.171821Z","caller":"traceutil/trace.go:171","msg":"trace[787180331] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"975.682499ms","start":"2026-08-11T15:03:27.196111Z","end":"2026-08-11T15:03:28.171793Z","steps":["trace[787180331] 'process raft request' (duration: 975.232331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:28.171906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:27.200731Z","time spent":"971.08592ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10921,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T15:03:28.172086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.372411ms","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-11T15:03:28.17217Z","caller":"traceutil/trace.go:171","msg":"trace[1026294274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"649.489344ms","start":"2026-08-11T15:03:27.522665Z","end":"2026-08-11T15:03:28.172154Z","steps":["trace[1026294274] 'agreement among raft nodes before linearized reading' (duration: 649.220659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:28.172214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:27.52265Z","time spent":"649.552915ms","remote":"127.0.0.1:35942","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-11T15:03:28.17251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.137048ms","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-11T15:03:28.172547Z","caller":"traceutil/trace.go:171","msg":"trace[146426457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1296; }","duration":"616.23277ms","start":"2026-08-11T15:03:27.556304Z","end":"2026-08-11T15:03:28.172536Z","steps":["trace[146426457] 'agreement among raft nodes before linearized reading' (duration: 616.132548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:28.172574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:27.55628Z","time spent":"616.287622ms","remote":"127.0.0.1:35942","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-11T15:03:28.172648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:27.196084Z","time spent":"975.793561ms","remote":"127.0.0.1:35958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T15:03:28.17265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.790888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-11T15:03:28.172777Z","caller":"traceutil/trace.go:171","msg":"trace[1507750880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1296; }","duration":"511.992532ms","start":"2026-08-11T15:03:27.660748Z","end":"2026-08-11T15:03:28.17274Z","steps":["trace[1507750880] 'agreement among raft nodes before linearized reading' (duration: 511.349479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:28.172682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.473724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-11T15:03:28.172865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:27.660722Z","time spent":"512.128075ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-11T15:03:28.172875Z","caller":"traceutil/trace.go:171","msg":"trace[644258680] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1296; }","duration":"741.712389ms","start":"2026-08-11T15:03:27.431136Z","end":"2026-08-11T15:03:28.172848Z","steps":["trace[644258680] 'agreement among raft nodes before linearized reading' (duration: 741.493395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:28.172846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.93122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-11T15:03:28.172948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:27.431112Z","time spent":"741.807951ms","remote":"127.0.0.1:58254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-08-11T15:03:28.172953Z","caller":"traceutil/trace.go:171","msg":"trace[2075928775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"513.106213ms","start":"2026-08-11T15:03:27.65982Z","end":"2026-08-11T15:03:28.172926Z","steps":["trace[2075928775] 'agreement among raft nodes before linearized reading' (duration: 512.745677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:03:28.173039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:03:27.65979Z","time spent":"513.239436ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T15:03:28.296834Z","caller":"traceutil/trace.go:171","msg":"trace[1585584253] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"117.457744ms","start":"2026-08-11T15:03:28.179337Z","end":"2026-08-11T15:03:28.296795Z","steps":["trace[1585584253] 'process raft request' (duration: 116.811542ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:03:28.296845Z","caller":"traceutil/trace.go:171","msg":"trace[1186469881] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"116.132038ms","start":"2026-08-11T15:03:28.180673Z","end":"2026-08-11T15:03:28.296805Z","steps":["trace[1186469881] 'process raft request' (duration: 115.803812ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:03:28.297131Z","caller":"traceutil/trace.go:171","msg":"trace[1838146711] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"117.004605ms","start":"2026-08-11T15:03:28.180103Z","end":"2026-08-11T15:03:28.297108Z","steps":["trace[1838146711] 'process raft request' (duration: 116.338512ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:03:28.297445Z","caller":"traceutil/trace.go:171","msg":"trace[344862896] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"116.988714ms","start":"2026-08-11T15:03:28.179706Z","end":"2026-08-11T15:03:28.296695Z","steps":["trace[344862896] 'process raft request' (duration: 116.691049ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:03:28.296617Z","caller":"traceutil/trace.go:171","msg":"trace[2009255385] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"112.10759ms","start":"2026-08-11T15:03:28.184477Z","end":"2026-08-11T15:03:28.296584Z","steps":["trace[2009255385] 'process raft request' (duration: 112.037999ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:06:25.132489Z","caller":"traceutil/trace.go:171","msg":"trace[690401259] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"100.897877ms","start":"2026-08-11T15:06:25.031563Z","end":"2026-08-11T15:06:25.13246Z","steps":["trace[690401259] 'process raft request' (duration: 100.762044ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:06:25.340138Z","caller":"traceutil/trace.go:171","msg":"trace[293027385] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2225; }","duration":"205.657617ms","start":"2026-08-11T15:06:25.13446Z","end":"2026-08-11T15:06:25.340118Z","steps":["trace[293027385] 'read index received' (duration: 205.442834ms)","trace[293027385] 'applied index is now lower than readState.Index' (duration: 213.833µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T15:06:25.340267Z","caller":"traceutil/trace.go:171","msg":"trace[620184662] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"307.800478ms","start":"2026-08-11T15:06:25.032452Z","end":"2026-08-11T15:06:25.340252Z","steps":["trace[620184662] 'process raft request' (duration: 307.479272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:06:25.340376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T15:06:25.032433Z","time spent":"307.870709ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T15:06:25.340482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.999124ms","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-11T15:06:25.34056Z","caller":"traceutil/trace.go:171","msg":"trace[222544920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2139; }","duration":"206.126506ms","start":"2026-08-11T15:06:25.134415Z","end":"2026-08-11T15:06:25.340542Z","steps":["trace[222544920] 'agreement among raft nodes before linearized reading' (duration: 205.932093ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:06:25.340571Z","caller":"traceutil/trace.go:171","msg":"trace[404566344] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"289.506263ms","start":"2026-08-11T15:06:25.051024Z","end":"2026-08-11T15:06:25.34053Z","steps":["trace[404566344] 'process raft request' (duration: 289.042665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:06:25.340699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.151141ms","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-11T15:06:25.340743Z","caller":"traceutil/trace.go:171","msg":"trace[1914429779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2139; }","duration":"199.214622ms","start":"2026-08-11T15:06:25.141518Z","end":"2026-08-11T15:06:25.340732Z","steps":["trace[1914429779] 'agreement among raft nodes before linearized reading' (duration: 199.08745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T15:06:25.340802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.954913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-11T15:06:25.340838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.761519ms","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:530"} {"level":"info","ts":"2026-08-11T15:06:25.340934Z","caller":"traceutil/trace.go:171","msg":"trace[2006647677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2139; }","duration":"205.091466ms","start":"2026-08-11T15:06:25.135803Z","end":"2026-08-11T15:06:25.340894Z","steps":["trace[2006647677] 'agreement among raft nodes before linearized reading' (duration: 204.77965ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T15:06:25.340937Z","caller":"traceutil/trace.go:171","msg":"trace[1201336881] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2139; }","duration":"203.880552ms","start":"2026-08-11T15:06:25.137035Z","end":"2026-08-11T15:06:25.340916Z","steps":["trace[1201336881] 'agreement among raft nodes before linearized reading' (duration: 203.654208ms)"],"step_count":1}