{"level":"warn","ts":"2026-07-14T10:30:44.802269Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T10:30:44.802352Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177: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.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-14T10:30:44.802419Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T10:30:44.802426Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-14T10:30:44.802441Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T10:30:44.803062Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-07-14T10:30:44.803131Z","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.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-14T10:30:44.808164Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.810294ms"} {"level":"info","ts":"2026-07-14T10:30:44.816206Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-07-14T10:30:44.816314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T10:30:44.816338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-07-14T10:30:44.816348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T10:30:44.816357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-07-14T10:30:44.816395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-07-14T10:30:44.825902Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T10:30:44.827449Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T10:30:44.828496Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-14T10:30:44.830326Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T10:30:44.830599Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T10:30:44.830708Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T10:30:44.830798Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T10:30:44.830856Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T10:30:44.832817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-07-14T10:30:44.833398Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-14T10:30:44.840723Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T10:30:44.840817Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-14T10:30:44.840882Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-14T10:30:44.84095Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T10:30:44.840987Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T10:30:45.516828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T10:30:45.516957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T10:30:45.51699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-07-14T10:30:45.517014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-07-14T10:30:45.517031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-14T10:30:45.517055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-07-14T10:30:45.517072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-14T10:30:45.518575Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T10:30:45.518644Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T10:30:45.51868Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T10:30:45.518755Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T10:30:45.519528Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T10:30:45.519586Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T10:30:45.51977Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T10:30:45.519883Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T10:30:45.519918Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T10:30:45.520386Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T10:30:45.521883Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"warn","ts":"2026-07-14T10:30:49.242593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.400095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T10:30:49.242716Z","caller":"traceutil/trace.go:171","msg":"trace[186545333] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:246; }","duration":"289.733624ms","start":"2026-07-14T10:30:48.952967Z","end":"2026-07-14T10:30:49.2427Z","steps":["trace[186545333] 'read index received' (duration: 38.621µs)","trace[186545333] 'applied index is now lower than readState.Index' (duration: 289.693853ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T10:30:49.242809Z","caller":"traceutil/trace.go:171","msg":"trace[166661742] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"495.231975ms","start":"2026-07-14T10:30:48.74755Z","end":"2026-07-14T10:30:49.242782Z","steps":["trace[166661742] 'process raft request' (duration: 190.152331ms)","trace[166661742] 'compare' (duration: 304.277611ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:30:49.242858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.891769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T10:30:49.242886Z","caller":"traceutil/trace.go:171","msg":"trace[551949089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:241; }","duration":"289.963061ms","start":"2026-07-14T10:30:48.952914Z","end":"2026-07-14T10:30:49.242877Z","steps":["trace[551949089] 'agreement among raft nodes before linearized reading' (duration: 289.886989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:30:49.24292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:30:48.747527Z","time spent":"495.339988ms","remote":"127.0.0.1:56346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T10:30:49.44215Z","caller":"traceutil/trace.go:171","msg":"trace[547423508] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"240.910371ms","start":"2026-07-14T10:30:49.201214Z","end":"2026-07-14T10:30:49.442124Z","steps":["trace[547423508] 'process raft request' (duration: 237.526377ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:30:49.443441Z","caller":"traceutil/trace.go:171","msg":"trace[1770303776] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"196.118489ms","start":"2026-07-14T10:30:49.247295Z","end":"2026-07-14T10:30:49.443414Z","steps":["trace[1770303776] 'process raft request' (duration: 196.072798ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:30:49.443784Z","caller":"traceutil/trace.go:171","msg":"trace[1895620110] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"196.522661ms","start":"2026-07-14T10:30:49.247247Z","end":"2026-07-14T10:30:49.44377Z","steps":["trace[1895620110] 'process raft request' (duration: 196.005057ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:31:21.144705Z","caller":"traceutil/trace.go:171","msg":"trace[1474527463] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"120.332185ms","start":"2026-07-14T10:31:21.02435Z","end":"2026-07-14T10:31:21.144682Z","steps":["trace[1474527463] 'process raft request' (duration: 118.399102ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:31:21.146405Z","caller":"traceutil/trace.go:171","msg":"trace[1601216714] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"101.980438ms","start":"2026-07-14T10:31:21.044415Z","end":"2026-07-14T10:31:21.146396Z","steps":["trace[1601216714] 'read index received' (duration: 50.252908ms)","trace[1601216714] 'applied index is now lower than readState.Index' (duration: 51.72703ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:31:21.146493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.067441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T10:31:21.146513Z","caller":"traceutil/trace.go:171","msg":"trace[366830650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"102.129943ms","start":"2026-07-14T10:31:21.044378Z","end":"2026-07-14T10:31:21.146508Z","steps":["trace[366830650] 'agreement among raft nodes before linearized reading' (duration: 102.066231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:31:22.588447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.607031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T10:31:22.588714Z","caller":"traceutil/trace.go:171","msg":"trace[1160078412] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"378.36556ms","start":"2026-07-14T10:31:22.210272Z","end":"2026-07-14T10:31:22.588638Z","steps":["trace[1160078412] 'process raft request' (duration: 103.483751ms)","trace[1160078412] 'compare' (duration: 274.403095ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:31:22.588907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:31:22.210248Z","time spent":"378.574935ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T10:31:22.588992Z","caller":"traceutil/trace.go:171","msg":"trace[242984184] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"310.657868ms","start":"2026-07-14T10:31:22.278308Z","end":"2026-07-14T10:31:22.588966Z","steps":["trace[242984184] 'read index received' (duration: 35.308306ms)","trace[242984184] 'applied index is now lower than readState.Index' (duration: 275.347222ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:31:22.58971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.386578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570096"} {"level":"info","ts":"2026-07-14T10:31:22.589754Z","caller":"traceutil/trace.go:171","msg":"trace[574752322] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:597; }","duration":"311.47278ms","start":"2026-07-14T10:31:22.278269Z","end":"2026-07-14T10:31:22.589741Z","steps":["trace[574752322] 'agreement among raft nodes before linearized reading' (duration: 310.829922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:31:22.58979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:31:22.278252Z","time spent":"311.529662ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":570118,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-07-14T10:31:22.590739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.725872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T10:31:22.590815Z","caller":"traceutil/trace.go:171","msg":"trace[1618103180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"309.831435ms","start":"2026-07-14T10:31:22.280963Z","end":"2026-07-14T10:31:22.590794Z","steps":["trace[1618103180] 'agreement among raft nodes before linearized reading' (duration: 309.64986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:31:22.590873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:31:22.280948Z","time spent":"309.912877ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T10:31:22.591019Z","caller":"traceutil/trace.go:171","msg":"trace[1556972398] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"303.818218ms","start":"2026-07-14T10:31:22.287162Z","end":"2026-07-14T10:31:22.59098Z","steps":["trace[1556972398] 'process raft request' (duration: 301.982198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:31:22.59133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:31:22.28714Z","time spent":"303.933182ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T10:31:27.069234Z","caller":"traceutil/trace.go:171","msg":"trace[958935342] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"202.291782ms","start":"2026-07-14T10:31:26.866913Z","end":"2026-07-14T10:31:27.069205Z","steps":["trace[958935342] 'process raft request' (duration: 201.543341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:31:27.651542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186446674056554,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T10:31:27.927526Z","caller":"traceutil/trace.go:171","msg":"trace[1316529588] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"776.408923ms","start":"2026-07-14T10:31:27.151093Z","end":"2026-07-14T10:31:27.927502Z","steps":["trace[1316529588] 'read index received' (duration: 772.951017ms)","trace[1316529588] 'applied index is now lower than readState.Index' (duration: 3.456666ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T10:31:27.927636Z","caller":"traceutil/trace.go:171","msg":"trace[4410823] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"787.104658ms","start":"2026-07-14T10:31:27.140498Z","end":"2026-07-14T10:31:27.927602Z","steps":["trace[4410823] 'process raft request' (duration: 783.532529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:31:27.927725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.607698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T10:31:27.927802Z","caller":"traceutil/trace.go:171","msg":"trace[1522551908] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:629; }","duration":"776.702701ms","start":"2026-07-14T10:31:27.151087Z","end":"2026-07-14T10:31:27.927789Z","steps":["trace[1522551908] 'agreement among raft nodes before linearized reading' (duration: 776.537936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:31:27.927829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:31:27.140481Z","time spent":"787.262432ms","remote":"127.0.0.1:56190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T10:31:27.92834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:31:27.151033Z","time spent":"777.289836ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-07-14T10:31:27.937964Z","caller":"traceutil/trace.go:171","msg":"trace[1578439835] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"154.626334ms","start":"2026-07-14T10:31:27.783306Z","end":"2026-07-14T10:31:27.937933Z","steps":["trace[1578439835] 'process raft request' (duration: 154.4604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:31:27.938032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.045322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T10:31:27.938125Z","caller":"traceutil/trace.go:171","msg":"trace[2004865441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"267.198656ms","start":"2026-07-14T10:31:27.670911Z","end":"2026-07-14T10:31:27.93811Z","steps":["trace[2004865441] 'agreement among raft nodes before linearized reading' (duration: 267.030531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:31:27.938267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.683618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T10:31:27.938296Z","caller":"traceutil/trace.go:171","msg":"trace[114381689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"269.085368ms","start":"2026-07-14T10:31:27.669201Z","end":"2026-07-14T10:31:27.938286Z","steps":["trace[114381689] 'agreement among raft nodes before linearized reading' (duration: 268.663736ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:31:42.057474Z","caller":"traceutil/trace.go:171","msg":"trace[2061732889] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"103.197862ms","start":"2026-07-14T10:31:41.95425Z","end":"2026-07-14T10:31:42.057448Z","steps":["trace[2061732889] 'process raft request' (duration: 103.015467ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:31:55.570431Z","caller":"traceutil/trace.go:171","msg":"trace[1207597797] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"188.706046ms","start":"2026-07-14T10:31:55.381698Z","end":"2026-07-14T10:31:55.570404Z","steps":["trace[1207597797] 'process raft request' (duration: 186.089894ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:31:55.785597Z","caller":"traceutil/trace.go:171","msg":"trace[936496828] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"108.681325ms","start":"2026-07-14T10:31:55.676894Z","end":"2026-07-14T10:31:55.785576Z","steps":["trace[936496828] 'process raft request' (duration: 108.476519ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:31:55.785777Z","caller":"traceutil/trace.go:171","msg":"trace[1594016974] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"105.645921ms","start":"2026-07-14T10:31:55.680122Z","end":"2026-07-14T10:31:55.785768Z","steps":["trace[1594016974] 'process raft request' (duration: 105.353573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:16.147791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.445344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T10:32:16.147893Z","caller":"traceutil/trace.go:171","msg":"trace[1925353162] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1006; }","duration":"114.602218ms","start":"2026-07-14T10:32:16.033271Z","end":"2026-07-14T10:32:16.147874Z","steps":["trace[1925353162] 'count revisions from in-memory index tree' (duration: 114.359081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:16.147959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.794781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-14T10:32:16.148094Z","caller":"traceutil/trace.go:171","msg":"trace[1814053202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1006; }","duration":"490.966656ms","start":"2026-07-14T10:32:15.657111Z","end":"2026-07-14T10:32:16.148077Z","steps":["trace[1814053202] 'range keys from in-memory index tree' (duration: 490.630896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:16.14814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:15.657094Z","time spent":"491.036277ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T10:32:16.148357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.583365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T10:32:16.148453Z","caller":"traceutil/trace.go:171","msg":"trace[1239315903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"115.753179ms","start":"2026-07-14T10:32:16.032679Z","end":"2026-07-14T10:32:16.148432Z","steps":["trace[1239315903] 'range keys from in-memory index tree' (duration: 115.491962ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:32:16.700665Z","caller":"traceutil/trace.go:171","msg":"trace[1047042829] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"547.049386ms","start":"2026-07-14T10:32:16.153591Z","end":"2026-07-14T10:32:16.700641Z","steps":["trace[1047042829] 'process raft request' (duration: 546.888002ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:32:16.700678Z","caller":"traceutil/trace.go:171","msg":"trace[1986378908] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"436.332855ms","start":"2026-07-14T10:32:16.264315Z","end":"2026-07-14T10:32:16.700648Z","steps":["trace[1986378908] 'read index received' (duration: 436.299925ms)","trace[1986378908] 'applied index is now lower than readState.Index' (duration: 30.52µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:32:16.700837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:16.153576Z","time spent":"547.167899ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T10:32:16.700965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.627733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-14T10:32:16.701005Z","caller":"traceutil/trace.go:171","msg":"trace[5862852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"436.722086ms","start":"2026-07-14T10:32:16.264274Z","end":"2026-07-14T10:32:16.700996Z","steps":["trace[5862852] 'agreement among raft nodes before linearized reading' (duration: 436.50813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:16.701043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:16.264259Z","time spent":"436.775427ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T10:32:16.702037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.679197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T10:32:16.702048Z","caller":"traceutil/trace.go:171","msg":"trace[569289307] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"307.832075ms","start":"2026-07-14T10:32:16.394187Z","end":"2026-07-14T10:32:16.702019Z","steps":["trace[569289307] 'process raft request' (duration: 307.64675ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:32:16.702098Z","caller":"traceutil/trace.go:171","msg":"trace[405028264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"419.7702ms","start":"2026-07-14T10:32:16.282315Z","end":"2026-07-14T10:32:16.702086Z","steps":["trace[405028264] 'agreement among raft nodes before linearized reading' (duration: 419.612626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:16.702129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:16.282305Z","time spent":"419.818091ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T10:32:16.702171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:16.394163Z","time spent":"307.948229ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T10:32:39.444745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.505549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T10:32:39.444848Z","caller":"traceutil/trace.go:171","msg":"trace[665018770] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"632.215326ms","start":"2026-07-14T10:32:38.812617Z","end":"2026-07-14T10:32:39.444832Z","steps":["trace[665018770] 'read index received' (duration: 163.483241ms)","trace[665018770] 'applied index is now lower than readState.Index' (duration: 468.730905ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T10:32:39.444933Z","caller":"traceutil/trace.go:171","msg":"trace[694045711] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"638.389706ms","start":"2026-07-14T10:32:38.806532Z","end":"2026-07-14T10:32:39.444922Z","steps":["trace[694045711] 'process raft request' (duration: 169.62237ms)","trace[694045711] 'compare' (duration: 468.350254ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:32:39.444992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:38.806506Z","time spent":"638.455667ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T10:32:39.445285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.39574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T10:32:39.445279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.359274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T10:32:39.445323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.658621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T10:32:39.445324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.50847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T10:32:39.445349Z","caller":"traceutil/trace.go:171","msg":"trace[830477120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1081; }","duration":"411.500323ms","start":"2026-07-14T10:32:39.033832Z","end":"2026-07-14T10:32:39.445332Z","steps":["trace[830477120] 'agreement among raft nodes before linearized reading' (duration: 411.407341ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:32:39.445373Z","caller":"traceutil/trace.go:171","msg":"trace[735696793] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1081; }","duration":"137.755194ms","start":"2026-07-14T10:32:39.307604Z","end":"2026-07-14T10:32:39.445359Z","steps":["trace[735696793] 'agreement among raft nodes before linearized reading' (duration: 137.664701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:39.445386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:39.033813Z","time spent":"411.566064ms","remote":"127.0.0.1:55998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T10:32:39.445375Z","caller":"traceutil/trace.go:171","msg":"trace[987304600] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1081; }","duration":"409.501418ms","start":"2026-07-14T10:32:39.035847Z","end":"2026-07-14T10:32:39.445349Z","steps":["trace[987304600] 'agreement among raft nodes before linearized reading' (duration: 409.362954ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:32:39.445384Z","caller":"traceutil/trace.go:171","msg":"trace[826597833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1081; }","duration":"598.637264ms","start":"2026-07-14T10:32:38.846731Z","end":"2026-07-14T10:32:39.445368Z","steps":["trace[826597833] 'agreement among raft nodes before linearized reading' (duration: 598.444969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:39.445414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.880003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-14T10:32:39.445435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:39.035825Z","time spent":"409.593661ms","remote":"127.0.0.1:46736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-14T10:32:39.445433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:38.846712Z","time spent":"598.711236ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-14T10:32:39.44548Z","caller":"traceutil/trace.go:171","msg":"trace[1865364191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"522.981476ms","start":"2026-07-14T10:32:38.92248Z","end":"2026-07-14T10:32:39.445462Z","steps":["trace[1865364191] 'agreement among raft nodes before linearized reading' (duration: 522.77427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:39.445526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:38.92246Z","time spent":"523.053467ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T10:32:39.445285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.654488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T10:32:39.445664Z","caller":"traceutil/trace.go:171","msg":"trace[1697067016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"633.060719ms","start":"2026-07-14T10:32:38.812587Z","end":"2026-07-14T10:32:39.445648Z","steps":["trace[1697067016] 'agreement among raft nodes before linearized reading' (duration: 632.574725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:39.445717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:38.812579Z","time spent":"633.124111ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T10:32:39.703053Z","caller":"traceutil/trace.go:171","msg":"trace[157097768] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"253.089622ms","start":"2026-07-14T10:32:39.449939Z","end":"2026-07-14T10:32:39.703029Z","steps":["trace[157097768] 'process raft request' (duration: 253.041721ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:32:39.703077Z","caller":"traceutil/trace.go:171","msg":"trace[1973050882] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"253.448382ms","start":"2026-07-14T10:32:39.449603Z","end":"2026-07-14T10:32:39.703052Z","steps":["trace[1973050882] 'process raft request' (duration: 253.253736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:40.025513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.783053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T10:32:40.025711Z","caller":"traceutil/trace.go:171","msg":"trace[1357927268] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"216.500248ms","start":"2026-07-14T10:32:39.809193Z","end":"2026-07-14T10:32:40.025694Z","steps":["trace[1357927268] 'read index received' (duration: 58.001µs)","trace[1357927268] 'applied index is now lower than readState.Index' (duration: 216.440547ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T10:32:40.025755Z","caller":"traceutil/trace.go:171","msg":"trace[310836881] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"292.896025ms","start":"2026-07-14T10:32:39.732827Z","end":"2026-07-14T10:32:40.025723Z","steps":["trace[310836881] 'process raft request' (duration: 47.811243ms)","trace[310836881] 'compare' (duration: 244.465705ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:32:40.026087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.758603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T10:32:40.025859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.657373ms","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":"info","ts":"2026-07-14T10:32:40.026151Z","caller":"traceutil/trace.go:171","msg":"trace[1380737388] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1086; }","duration":"205.824405ms","start":"2026-07-14T10:32:39.820301Z","end":"2026-07-14T10:32:40.026126Z","steps":["trace[1380737388] 'agreement among raft nodes before linearized reading' (duration: 205.709032ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:32:40.026202Z","caller":"traceutil/trace.go:171","msg":"trace[1953760243] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1086; }","duration":"217.030753ms","start":"2026-07-14T10:32:39.80914Z","end":"2026-07-14T10:32:40.026171Z","steps":["trace[1953760243] 'agreement among raft nodes before linearized reading' (duration: 216.660773ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:32:55.091468Z","caller":"traceutil/trace.go:171","msg":"trace[797541631] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"214.374318ms","start":"2026-07-14T10:32:54.877076Z","end":"2026-07-14T10:32:55.091451Z","steps":["trace[797541631] 'process raft request' (duration: 213.992958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:56.283101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186446674057761,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T10:32:56.286879Z","caller":"traceutil/trace.go:171","msg":"trace[881450384] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1238; }","duration":"504.761964ms","start":"2026-07-14T10:32:55.782094Z","end":"2026-07-14T10:32:56.286856Z","steps":["trace[881450384] 'read index received' (duration: 504.568929ms)","trace[881450384] 'applied index is now lower than readState.Index' (duration: 192.065µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T10:32:56.287134Z","caller":"traceutil/trace.go:171","msg":"trace[39523220] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"507.15709ms","start":"2026-07-14T10:32:55.779947Z","end":"2026-07-14T10:32:56.287104Z","steps":["trace[39523220] 'process raft request' (duration: 506.741729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:56.287252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.518665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-14T10:32:56.287283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:55.779931Z","time spent":"507.270604ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T10:32:56.287288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.173946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T10:32:56.287359Z","caller":"traceutil/trace.go:171","msg":"trace[1244667757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1188; }","duration":"505.29824ms","start":"2026-07-14T10:32:55.782041Z","end":"2026-07-14T10:32:56.28734Z","steps":["trace[1244667757] 'agreement among raft nodes before linearized reading' (duration: 505.082444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:56.287396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:55.78202Z","time spent":"505.368541ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T10:32:56.287311Z","caller":"traceutil/trace.go:171","msg":"trace[265981345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1188; }","duration":"197.625858ms","start":"2026-07-14T10:32:56.08967Z","end":"2026-07-14T10:32:56.287296Z","steps":["trace[265981345] 'agreement among raft nodes before linearized reading' (duration: 197.424773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:56.962841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.030242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T10:32:56.962956Z","caller":"traceutil/trace.go:171","msg":"trace[2067837958] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1239; }","duration":"311.518976ms","start":"2026-07-14T10:32:56.651426Z","end":"2026-07-14T10:32:56.962945Z","steps":["trace[2067837958] 'read index received' (duration: 309.053589ms)","trace[2067837958] 'applied index is now lower than readState.Index' (duration: 2.464607ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:32:56.963024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.594458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T10:32:56.963044Z","caller":"traceutil/trace.go:171","msg":"trace[1865120023] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1190; }","duration":"311.63944ms","start":"2026-07-14T10:32:56.651397Z","end":"2026-07-14T10:32:56.963037Z","steps":["trace[1865120023] 'agreement among raft nodes before linearized reading' (duration: 311.578528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:56.963068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:56.651385Z","time spent":"311.676501ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-14T10:32:56.963089Z","caller":"traceutil/trace.go:171","msg":"trace[25647172] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"671.510144ms","start":"2026-07-14T10:32:56.291549Z","end":"2026-07-14T10:32:56.963059Z","steps":["trace[25647172] 'process raft request' (duration: 257.206945ms)","trace[25647172] 'compare' (duration: 413.847127ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T10:32:56.963094Z","caller":"traceutil/trace.go:171","msg":"trace[1341377603] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"671.388591ms","start":"2026-07-14T10:32:56.291676Z","end":"2026-07-14T10:32:56.963065Z","steps":["trace[1341377603] 'process raft request' (duration: 671.235006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:32:56.963227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:56.291529Z","time spent":"671.623417ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T10:32:56.963245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:32:56.291655Z","time spent":"671.509834ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T10:33:09.936546Z","caller":"traceutil/trace.go:171","msg":"trace[543195163] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"276.571378ms","start":"2026-07-14T10:33:09.659952Z","end":"2026-07-14T10:33:09.936523Z","steps":["trace[543195163] 'read index received' (duration: 276.434754ms)","trace[543195163] 'applied index is now lower than readState.Index' (duration: 135.544µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T10:33:09.936596Z","caller":"traceutil/trace.go:171","msg":"trace[828401227] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"345.810262ms","start":"2026-07-14T10:33:09.590766Z","end":"2026-07-14T10:33:09.936576Z","steps":["trace[828401227] 'process raft request' (duration: 345.628557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:09.936718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:09.590741Z","time spent":"345.902885ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T10:33:09.960865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.500325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T10:33:09.960957Z","caller":"traceutil/trace.go:171","msg":"trace[1700003206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"212.643119ms","start":"2026-07-14T10:33:09.748288Z","end":"2026-07-14T10:33:09.960931Z","steps":["trace[1700003206] 'agreement among raft nodes before linearized reading' (duration: 188.421926ms)","trace[1700003206] 'range keys from in-memory index tree' (duration: 24.053648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:33:09.960978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.000576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T10:33:09.96105Z","caller":"traceutil/trace.go:171","msg":"trace[79028631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1232; }","duration":"301.082378ms","start":"2026-07-14T10:33:09.659948Z","end":"2026-07-14T10:33:09.96103Z","steps":["trace[79028631] 'agreement among raft nodes before linearized reading' (duration: 276.695761ms)","trace[79028631] 'range keys from in-memory index tree' (duration: 24.169972ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:33:09.961093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:09.659911Z","time spent":"301.172801ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T10:33:13.861276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.406945ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-14T10:33:14.410998Z","caller":"traceutil/trace.go:171","msg":"trace[601734076] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"428.143365ms","start":"2026-07-14T10:33:13.982832Z","end":"2026-07-14T10:33:14.410975Z","steps":["trace[601734076] 'read index received' (duration: 428.113564ms)","trace[601734076] 'applied index is now lower than readState.Index' (duration: 28.401µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T10:33:14.411165Z","caller":"traceutil/trace.go:171","msg":"trace[606381911] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"451.059872ms","start":"2026-07-14T10:33:13.960063Z","end":"2026-07-14T10:33:14.411123Z","steps":["trace[606381911] 'process raft request' (duration: 450.742794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:14.411273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.411633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T10:33:14.411307Z","caller":"traceutil/trace.go:171","msg":"trace[1787585324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1265; }","duration":"428.510306ms","start":"2026-07-14T10:33:13.982787Z","end":"2026-07-14T10:33:14.411298Z","steps":["trace[1787585324] 'agreement among raft nodes before linearized reading' (duration: 428.351182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:14.411358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:13.982773Z","time spent":"428.577398ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T10:33:14.41139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:13.960047Z","time spent":"451.237188ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T10:33:14.428229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.912459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T10:33:14.428323Z","caller":"traceutil/trace.go:171","msg":"trace[946847588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"394.075213ms","start":"2026-07-14T10:33:14.034229Z","end":"2026-07-14T10:33:14.428304Z","steps":["trace[946847588] 'agreement among raft nodes before linearized reading' (duration: 393.897288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:14.428365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:14.034214Z","time spent":"394.142425ms","remote":"127.0.0.1:55998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T10:33:14.428604Z","caller":"traceutil/trace.go:171","msg":"trace[2041781373] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"392.038077ms","start":"2026-07-14T10:33:14.036537Z","end":"2026-07-14T10:33:14.428575Z","steps":["trace[2041781373] 'process raft request' (duration: 391.41125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:14.428751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:14.036528Z","time spent":"392.149311ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T10:33:15.20346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.407995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T10:33:15.203583Z","caller":"traceutil/trace.go:171","msg":"trace[429465323] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"221.629284ms","start":"2026-07-14T10:33:14.981928Z","end":"2026-07-14T10:33:15.203558Z","steps":["trace[429465323] 'compare' (duration: 215.151148ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:33:15.203654Z","caller":"traceutil/trace.go:171","msg":"trace[1699277912] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"210.024467ms","start":"2026-07-14T10:33:14.993617Z","end":"2026-07-14T10:33:15.203642Z","steps":["trace[1699277912] 'read index received' (duration: 18.63µs)","trace[1699277912] 'applied index is now lower than readState.Index' (duration: 210.004067ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:33:15.203788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.15427ms","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-07-14T10:33:15.20381Z","caller":"traceutil/trace.go:171","msg":"trace[1200147827] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1273; }","duration":"210.208002ms","start":"2026-07-14T10:33:14.993597Z","end":"2026-07-14T10:33:15.203805Z","steps":["trace[1200147827] 'agreement among raft nodes before linearized reading' (duration: 210.110239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:15.204022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.375349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-14T10:33:15.204064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.101815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-14T10:33:15.204075Z","caller":"traceutil/trace.go:171","msg":"trace[211773978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1273; }","duration":"137.490292ms","start":"2026-07-14T10:33:15.066567Z","end":"2026-07-14T10:33:15.204057Z","steps":["trace[211773978] 'agreement among raft nodes before linearized reading' (duration: 137.330798ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:33:15.204102Z","caller":"traceutil/trace.go:171","msg":"trace[394201219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"132.186857ms","start":"2026-07-14T10:33:15.071904Z","end":"2026-07-14T10:33:15.204091Z","steps":["trace[394201219] 'agreement among raft nodes before linearized reading' (duration: 132.011452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:15.709424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.76277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T10:33:15.709536Z","caller":"traceutil/trace.go:171","msg":"trace[159295777] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"502.453768ms","start":"2026-07-14T10:33:15.207061Z","end":"2026-07-14T10:33:15.709514Z","steps":["trace[159295777] 'process raft request' (duration: 167.534995ms)","trace[159295777] 'compare' (duration: 334.379548ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:33:15.70979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:15.207044Z","time spent":"502.708315ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T10:33:15.774894Z","caller":"traceutil/trace.go:171","msg":"trace[971062165] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1329; }","duration":"284.032402ms","start":"2026-07-14T10:33:15.490834Z","end":"2026-07-14T10:33:15.774866Z","steps":["trace[971062165] 'read index received' (duration: 283.849287ms)","trace[971062165] 'applied index is now lower than readState.Index' (duration: 182.135µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T10:33:15.774955Z","caller":"traceutil/trace.go:171","msg":"trace[263056203] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"567.824837ms","start":"2026-07-14T10:33:15.207097Z","end":"2026-07-14T10:33:15.774922Z","steps":["trace[263056203] 'process raft request' (duration: 567.594101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:15.775052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.195746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T10:33:15.775025Z","caller":"traceutil/trace.go:171","msg":"trace[118378823] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"566.317866ms","start":"2026-07-14T10:33:15.208658Z","end":"2026-07-14T10:33:15.774976Z","steps":["trace[118378823] 'process raft request' (duration: 566.171122ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:33:15.775095Z","caller":"traceutil/trace.go:171","msg":"trace[1482092614] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1276; }","duration":"284.308518ms","start":"2026-07-14T10:33:15.490775Z","end":"2026-07-14T10:33:15.775083Z","steps":["trace[1482092614] 'agreement among raft nodes before linearized reading' (duration: 284.193305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:15.775111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:15.207083Z","time spent":"567.94454ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T10:33:15.775205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:15.208647Z","time spent":"566.465679ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T10:33:16.565619Z","caller":"traceutil/trace.go:171","msg":"trace[1427368076] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"146.211511ms","start":"2026-07-14T10:33:16.419395Z","end":"2026-07-14T10:33:16.565606Z","steps":["trace[1427368076] 'process raft request' (duration: 145.985475ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:33:16.565541Z","caller":"traceutil/trace.go:171","msg":"trace[746042539] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"126.091641ms","start":"2026-07-14T10:33:16.439427Z","end":"2026-07-14T10:33:16.565518Z","steps":["trace[746042539] 'read index received' (duration: 125.874525ms)","trace[746042539] 'applied index is now lower than readState.Index' (duration: 216.196µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T10:33:16.565878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.42207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T10:33:16.566061Z","caller":"traceutil/trace.go:171","msg":"trace[1577376430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1279; }","duration":"126.653246ms","start":"2026-07-14T10:33:16.439389Z","end":"2026-07-14T10:33:16.566042Z","steps":["trace[1577376430] 'agreement among raft nodes before linearized reading' (duration: 126.312387ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T10:33:16.972399Z","caller":"traceutil/trace.go:171","msg":"trace[1480654508] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"401.122956ms","start":"2026-07-14T10:33:16.571252Z","end":"2026-07-14T10:33:16.972375Z","steps":["trace[1480654508] 'process raft request' (duration: 400.983312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:16.97256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:16.571228Z","time spent":"401.243659ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T10:33:16.984539Z","caller":"traceutil/trace.go:171","msg":"trace[1446685898] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"413.231898ms","start":"2026-07-14T10:33:16.571284Z","end":"2026-07-14T10:33:16.984516Z","steps":["trace[1446685898] 'process raft request' (duration: 413.092314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:16.984815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:16.571254Z","time spent":"413.407552ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T10:33:24.974383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.705423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-14T10:33:24.974478Z","caller":"traceutil/trace.go:171","msg":"trace[1332049846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"340.852877ms","start":"2026-07-14T10:33:24.633607Z","end":"2026-07-14T10:33:24.97446Z","steps":["trace[1332049846] 'range keys from in-memory index tree' (duration: 340.490057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:24.974546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:24.633588Z","time spent":"340.919299ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T10:33:24.974405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.491502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-14T10:33:24.974653Z","caller":"traceutil/trace.go:171","msg":"trace[1735668317] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1342; }","duration":"194.844512ms","start":"2026-07-14T10:33:24.779786Z","end":"2026-07-14T10:33:24.974631Z","steps":["trace[1735668317] 'range keys from in-memory index tree' (duration: 194.233745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:25.502555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186446674058076,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T10:33:25.532991Z","caller":"traceutil/trace.go:171","msg":"trace[586744161] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"553.561298ms","start":"2026-07-14T10:33:24.979406Z","end":"2026-07-14T10:33:25.532967Z","steps":["trace[586744161] 'process raft request' (duration: 553.397653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:25.533255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:24.979385Z","time spent":"553.66379ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T10:33:25.731645Z","caller":"traceutil/trace.go:171","msg":"trace[1651172159] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1399; }","duration":"730.092918ms","start":"2026-07-14T10:33:25.001533Z","end":"2026-07-14T10:33:25.731626Z","steps":["trace[1651172159] 'read index received' (duration: 531.279428ms)","trace[1651172159] 'applied index is now lower than readState.Index' (duration: 198.81262ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T10:33:25.731717Z","caller":"traceutil/trace.go:171","msg":"trace[590544066] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"490.152052ms","start":"2026-07-14T10:33:25.241549Z","end":"2026-07-14T10:33:25.731701Z","steps":["trace[590544066] 'process raft request' (duration: 490.040069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:25.731842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:25.241524Z","time spent":"490.246855ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T10:33:25.731705Z","caller":"traceutil/trace.go:171","msg":"trace[788251401] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"752.023658ms","start":"2026-07-14T10:33:24.979644Z","end":"2026-07-14T10:33:25.731668Z","steps":["trace[788251401] 'process raft request' (duration: 751.780191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:25.732064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.448208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-14T10:33:25.732172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.564941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T10:33:25.732182Z","caller":"traceutil/trace.go:171","msg":"trace[1838239097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1345; }","duration":"730.625613ms","start":"2026-07-14T10:33:25.001506Z","end":"2026-07-14T10:33:25.732132Z","steps":["trace[1838239097] 'agreement among raft nodes before linearized reading' (duration: 730.227432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:25.73225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.902575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-14T10:33:25.732293Z","caller":"traceutil/trace.go:171","msg":"trace[868346949] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1345; }","duration":"326.980947ms","start":"2026-07-14T10:33:25.405302Z","end":"2026-07-14T10:33:25.732283Z","steps":["trace[868346949] 'agreement among raft nodes before linearized reading' (duration: 326.834373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:25.732305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:25.001493Z","time spent":"730.794187ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T10:33:25.732284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:24.979628Z","time spent":"752.574694ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T10:33:25.732333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:25.405284Z","time spent":"327.040799ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-14T10:33:25.732347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.686469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T10:33:25.732446Z","caller":"traceutil/trace.go:171","msg":"trace[1716023019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1345; }","duration":"698.832243ms","start":"2026-07-14T10:33:25.033592Z","end":"2026-07-14T10:33:25.732424Z","steps":["trace[1716023019] 'agreement among raft nodes before linearized reading' (duration: 698.660018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:25.73249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:25.033573Z","time spent":"698.908515ms","remote":"127.0.0.1:55998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T10:33:25.732245Z","caller":"traceutil/trace.go:171","msg":"trace[246474840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1345; }","duration":"715.690795ms","start":"2026-07-14T10:33:25.01654Z","end":"2026-07-14T10:33:25.732231Z","steps":["trace[246474840] 'agreement among raft nodes before linearized reading' (duration: 715.51039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T10:33:25.733123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T10:33:25.016532Z","time spent":"716.574828ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}