{"level":"warn","ts":"2026-01-29T16:07:19.113332Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T16:07:19.113398Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-01-29T16:07:19.113894Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T16:07:19.114372Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-01-29T16:07:19.114455Z","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-01-29T16:07:19.115527Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-01-29T16:07:19.11622Z","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":2,"max-cpu-available":2,"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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-01-29T16:07:19.122859Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.884269ms"} {"level":"info","ts":"2026-01-29T16:07:19.141206Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-01-29T16:07:19.141309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T16:07:19.141333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-01-29T16:07:19.141375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T16:07:19.141385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-01-29T16:07:19.141478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-01-29T16:07:19.151063Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T16:07:19.155547Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T16:07:19.158601Z","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-01-29T16:07:19.162123Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T16:07:19.165912Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T16:07:19.166206Z","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-01-29T16:07:19.16626Z","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-01-29T16:07:19.166348Z","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-01-29T16:07:19.166779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-01-29T16:07:19.166966Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-01-29T16:07:19.176017Z","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-01-29T16:07:19.176341Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T16:07:19.176386Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T16:07:19.176716Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-01-29T16:07:19.176738Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-01-29T16:07:20.042809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T16:07:20.04287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T16:07:20.042898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-01-29T16:07:20.042913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-01-29T16:07:20.042928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-01-29T16:07:20.042939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-01-29T16:07:20.042948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-01-29T16:07:20.046149Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T16:07:20.046253Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T16:07:20.046334Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T16:07:20.046557Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T16:07:20.046637Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T16:07:20.04665Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T16:07:20.04799Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T16:07:20.049486Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-01-29T16:07:20.049551Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T16:07:20.049604Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T16:07:20.049615Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-01-29T16:07:43.725359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530062541023416,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T16:07:43.772329Z","caller":"traceutil/trace.go:171","msg":"trace[1156918093] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"548.497637ms","start":"2026-01-29T16:07:43.223808Z","end":"2026-01-29T16:07:43.772305Z","steps":["trace[1156918093] 'read index received' (duration: 548.317449ms)","trace[1156918093] 'applied index is now lower than readState.Index' (duration: 179.428µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:07:43.772639Z","caller":"traceutil/trace.go:171","msg":"trace[194255307] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"593.7791ms","start":"2026-01-29T16:07:43.178848Z","end":"2026-01-29T16:07:43.772628Z","steps":["trace[194255307] 'process raft request' (duration: 593.336843ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:43.773193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:43.178812Z","time spent":"593.854532ms","remote":"127.0.0.1:54454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T16:07:43.773411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.607059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-29T16:07:43.773454Z","caller":"traceutil/trace.go:171","msg":"trace[1426108903] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:424; }","duration":"549.689703ms","start":"2026-01-29T16:07:43.223752Z","end":"2026-01-29T16:07:43.773442Z","steps":["trace[1426108903] 'agreement among raft nodes before linearized reading' (duration: 549.61973ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:43.773485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:43.223725Z","time spent":"549.751095ms","remote":"127.0.0.1:54224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-01-29T16:07:43.77364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.556984ms","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-01-29T16:07:43.773665Z","caller":"traceutil/trace.go:171","msg":"trace[275911240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"446.632257ms","start":"2026-01-29T16:07:43.327025Z","end":"2026-01-29T16:07:43.773658Z","steps":["trace[275911240] 'agreement among raft nodes before linearized reading' (duration: 446.577695ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:43.77369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:43.327002Z","time spent":"446.682919ms","remote":"127.0.0.1:54454","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-01-29T16:07:43.773876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.333223ms","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-01-29T16:07:43.773904Z","caller":"traceutil/trace.go:171","msg":"trace[286498265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"191.415696ms","start":"2026-01-29T16:07:43.58248Z","end":"2026-01-29T16:07:43.773896Z","steps":["trace[286498265] 'agreement among raft nodes before linearized reading' (duration: 191.365824ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:07:43.988766Z","caller":"traceutil/trace.go:171","msg":"trace[657490992] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"203.964269ms","start":"2026-01-29T16:07:43.784786Z","end":"2026-01-29T16:07:43.98875Z","steps":["trace[657490992] 'process raft request' (duration: 201.980933ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:07:43.991267Z","caller":"traceutil/trace.go:171","msg":"trace[792531727] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:438; }","duration":"106.246589ms","start":"2026-01-29T16:07:43.885011Z","end":"2026-01-29T16:07:43.991257Z","steps":["trace[792531727] 'read index received' (duration: 48.108871ms)","trace[792531727] 'applied index is now lower than readState.Index' (duration: 58.137138ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:07:43.991521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.498718ms","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-01-29T16:07:43.991701Z","caller":"traceutil/trace.go:171","msg":"trace[373377806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"106.707737ms","start":"2026-01-29T16:07:43.884983Z","end":"2026-01-29T16:07:43.991691Z","steps":["trace[373377806] 'agreement among raft nodes before linearized reading' (duration: 106.460347ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:07:43.99223Z","caller":"traceutil/trace.go:171","msg":"trace[2014684748] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"206.074811ms","start":"2026-01-29T16:07:43.786145Z","end":"2026-01-29T16:07:43.99222Z","steps":["trace[2014684748] 'process raft request' (duration: 202.040516ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:07:50.079554Z","caller":"traceutil/trace.go:171","msg":"trace[1005279526] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"277.950659ms","start":"2026-01-29T16:07:49.801582Z","end":"2026-01-29T16:07:50.079533Z","steps":["trace[1005279526] 'read index received' (duration: 277.664929ms)","trace[1005279526] 'applied index is now lower than readState.Index' (duration: 285µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:07:50.079761Z","caller":"traceutil/trace.go:171","msg":"trace[1964811276] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"302.149718ms","start":"2026-01-29T16:07:49.777591Z","end":"2026-01-29T16:07:50.07974Z","steps":["trace[1964811276] 'process raft request' (duration: 301.736514ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:50.079865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:49.777571Z","time spent":"302.239182ms","remote":"127.0.0.1:54378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T16:07:50.079981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.429675ms","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-01-29T16:07:50.080053Z","caller":"traceutil/trace.go:171","msg":"trace[1122702350] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:473; }","duration":"278.589062ms","start":"2026-01-29T16:07:49.801452Z","end":"2026-01-29T16:07:50.080041Z","steps":["trace[1122702350] 'agreement among raft nodes before linearized reading' (duration: 278.298182ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:07:51.50152Z","caller":"traceutil/trace.go:171","msg":"trace[411023606] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"107.746213ms","start":"2026-01-29T16:07:51.393758Z","end":"2026-01-29T16:07:51.501504Z","steps":["trace[411023606] 'read index received' (duration: 107.617288ms)","trace[411023606] 'applied index is now lower than readState.Index' (duration: 128.375µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:07:51.501635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.860077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:07:51.501656Z","caller":"traceutil/trace.go:171","msg":"trace[1966150606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"107.922219ms","start":"2026-01-29T16:07:51.393728Z","end":"2026-01-29T16:07:51.50165Z","steps":["trace[1966150606] 'agreement among raft nodes before linearized reading' (duration: 107.836056ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:07:51.501844Z","caller":"traceutil/trace.go:171","msg":"trace[962205600] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"117.36487ms","start":"2026-01-29T16:07:51.384474Z","end":"2026-01-29T16:07:51.501839Z","steps":["trace[962205600] 'process raft request' (duration: 116.944845ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:07:51.764179Z","caller":"traceutil/trace.go:171","msg":"trace[1817788573] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"142.992639ms","start":"2026-01-29T16:07:51.621171Z","end":"2026-01-29T16:07:51.764163Z","steps":["trace[1817788573] 'process raft request' (duration: 142.74191ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:52.594552Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530062541023565,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T16:07:52.783Z","caller":"traceutil/trace.go:171","msg":"trace[1897249516] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"999.492898ms","start":"2026-01-29T16:07:51.783493Z","end":"2026-01-29T16:07:52.782986Z","steps":["trace[1897249516] 'process raft request' (duration: 926.579527ms)","trace[1897249516] 'compare' (duration: 72.848518ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:07:52.783074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:51.783478Z","time spent":"999.561139ms","remote":"127.0.0.1:54232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T16:07:52.786566Z","caller":"traceutil/trace.go:171","msg":"trace[533606465] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:522; }","duration":"694.414781ms","start":"2026-01-29T16:07:52.092143Z","end":"2026-01-29T16:07:52.786557Z","steps":["trace[533606465] 'read index received' (duration: 617.935577ms)","trace[533606465] 'applied index is now lower than readState.Index' (duration: 76.478674ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:07:52.786703Z","caller":"traceutil/trace.go:171","msg":"trace[1432283159] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"803.649051ms","start":"2026-01-29T16:07:51.983049Z","end":"2026-01-29T16:07:52.786698Z","steps":["trace[1432283159] 'process raft request' (duration: 803.349571ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:52.786746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:51.983028Z","time spent":"803.693133ms","remote":"127.0.0.1:54232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T16:07:52.786873Z","caller":"traceutil/trace.go:171","msg":"trace[404289112] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"438.871602ms","start":"2026-01-29T16:07:52.347997Z","end":"2026-01-29T16:07:52.786869Z","steps":["trace[404289112] 'process raft request' (duration: 438.442207ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:52.786909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:52.347981Z","time spent":"438.907113ms","remote":"127.0.0.1:54454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T16:07:52.786968Z","caller":"traceutil/trace.go:171","msg":"trace[51097458] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"403.035477ms","start":"2026-01-29T16:07:52.383928Z","end":"2026-01-29T16:07:52.786964Z","steps":["trace[51097458] 'process raft request' (duration: 402.54152ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:52.786991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:52.38391Z","time spent":"403.072119ms","remote":"127.0.0.1:54232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T16:07:52.787041Z","caller":"traceutil/trace.go:171","msg":"trace[914418295] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"121.429324ms","start":"2026-01-29T16:07:52.665608Z","end":"2026-01-29T16:07:52.787037Z","steps":["trace[914418295] 'process raft request' (duration: 120.909996ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:52.78713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.986542ms","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-01-29T16:07:52.787149Z","caller":"traceutil/trace.go:171","msg":"trace[1272952850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"695.024873ms","start":"2026-01-29T16:07:52.09212Z","end":"2026-01-29T16:07:52.787145Z","steps":["trace[1272952850] 'agreement among raft nodes before linearized reading' (duration: 694.96434ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:52.787162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:52.092108Z","time spent":"695.051654ms","remote":"127.0.0.1:54454","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-01-29T16:07:52.787239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.043198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T16:07:52.787336Z","caller":"traceutil/trace.go:171","msg":"trace[796945763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"694.147793ms","start":"2026-01-29T16:07:52.093183Z","end":"2026-01-29T16:07:52.787331Z","steps":["trace[796945763] 'agreement among raft nodes before linearized reading' (duration: 694.029629ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:52.787359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:52.093179Z","time spent":"694.175624ms","remote":"127.0.0.1:54454","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-01-29T16:07:52.787666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.504179ms","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-01-29T16:07:52.787687Z","caller":"traceutil/trace.go:171","msg":"trace[337611247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:511; }","duration":"340.557971ms","start":"2026-01-29T16:07:52.447124Z","end":"2026-01-29T16:07:52.787682Z","steps":["trace[337611247] 'agreement among raft nodes before linearized reading' (duration: 340.513269ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:52.787699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:52.447106Z","time spent":"340.589871ms","remote":"127.0.0.1:54454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T16:07:52.789486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.027081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:07:52.789511Z","caller":"traceutil/trace.go:171","msg":"trace[1032903621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"393.076452ms","start":"2026-01-29T16:07:52.396428Z","end":"2026-01-29T16:07:52.789504Z","steps":["trace[1032903621] 'agreement among raft nodes before linearized reading' (duration: 393.041471ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:52.789525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:52.396414Z","time spent":"393.107414ms","remote":"127.0.0.1:54176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T16:07:52.789981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.38163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:07:52.799203Z","caller":"traceutil/trace.go:171","msg":"trace[457498176] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:511; }","duration":"618.531348ms","start":"2026-01-29T16:07:52.180581Z","end":"2026-01-29T16:07:52.799112Z","steps":["trace[457498176] 'agreement among raft nodes before linearized reading' (duration: 609.381011ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:52.799363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:52.180576Z","time spent":"618.767176ms","remote":"127.0.0.1:54232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-29T16:07:52.790025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.300062ms","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-01-29T16:07:52.799925Z","caller":"traceutil/trace.go:171","msg":"trace[1557053574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"622.218986ms","start":"2026-01-29T16:07:52.177699Z","end":"2026-01-29T16:07:52.799918Z","steps":["trace[1557053574] 'agreement among raft nodes before linearized reading' (duration: 612.295092ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:52.799999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:52.177687Z","time spent":"622.303358ms","remote":"127.0.0.1:54454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T16:07:52.790054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.556522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T16:07:52.800324Z","caller":"traceutil/trace.go:171","msg":"trace[1919318343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"684.843669ms","start":"2026-01-29T16:07:52.115474Z","end":"2026-01-29T16:07:52.800317Z","steps":["trace[1919318343] 'agreement among raft nodes before linearized reading' (duration: 674.558022ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:52.800445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:52.115462Z","time spent":"684.974363ms","remote":"127.0.0.1:54454","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-01-29T16:07:59.207568Z","caller":"traceutil/trace.go:171","msg":"trace[828219237] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"269.870701ms","start":"2026-01-29T16:07:58.937681Z","end":"2026-01-29T16:07:59.207552Z","steps":["trace[828219237] 'read index received' (duration: 269.746457ms)","trace[828219237] 'applied index is now lower than readState.Index' (duration: 123.234µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:07:59.207653Z","caller":"traceutil/trace.go:171","msg":"trace[2110885095] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"270.255723ms","start":"2026-01-29T16:07:58.93739Z","end":"2026-01-29T16:07:59.207645Z","steps":["trace[2110885095] 'process raft request' (duration: 270.037676ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:59.207811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.15165ms","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-01-29T16:07:59.207827Z","caller":"traceutil/trace.go:171","msg":"trace[1378158004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"270.189212ms","start":"2026-01-29T16:07:58.937632Z","end":"2026-01-29T16:07:59.207822Z","steps":["trace[1378158004] 'agreement among raft nodes before linearized reading' (duration: 270.105789ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:59.207908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.950342ms","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-01-29T16:07:59.207921Z","caller":"traceutil/trace.go:171","msg":"trace[1894650154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:564; }","duration":"220.989203ms","start":"2026-01-29T16:07:58.986928Z","end":"2026-01-29T16:07:59.207917Z","steps":["trace[1894650154] 'agreement among raft nodes before linearized reading' (duration: 220.937551ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:59.207982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.982374ms","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-01-29T16:07:59.207995Z","caller":"traceutil/trace.go:171","msg":"trace[1003032014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"221.001424ms","start":"2026-01-29T16:07:58.98699Z","end":"2026-01-29T16:07:59.207991Z","steps":["trace[1003032014] 'agreement among raft nodes before linearized reading' (duration: 220.966423ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:59.208046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.060347ms","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-01-29T16:07:59.208056Z","caller":"traceutil/trace.go:171","msg":"trace[1259868815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"221.077807ms","start":"2026-01-29T16:07:58.986975Z","end":"2026-01-29T16:07:59.208053Z","steps":["trace[1259868815] 'agreement among raft nodes before linearized reading' (duration: 221.049686ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:07:59.489827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.079615ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-29T16:07:59.490801Z","caller":"traceutil/trace.go:171","msg":"trace[397899191] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"278.040871ms","start":"2026-01-29T16:07:59.212722Z","end":"2026-01-29T16:07:59.490763Z","steps":["trace[397899191] 'process raft request' (duration: 277.246616ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:07:59.490936Z","caller":"traceutil/trace.go:171","msg":"trace[1007821527] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"273.920268ms","start":"2026-01-29T16:07:59.216978Z","end":"2026-01-29T16:07:59.490898Z","steps":["trace[1007821527] 'process raft request' (duration: 273.224915ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:07:59.49161Z","caller":"traceutil/trace.go:171","msg":"trace[608710060] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"278.852398ms","start":"2026-01-29T16:07:59.212748Z","end":"2026-01-29T16:07:59.4916Z","steps":["trace[608710060] 'process raft request' (duration: 277.38836ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.430852Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530062541023720,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T16:08:00.718148Z","caller":"traceutil/trace.go:171","msg":"trace[1389515366] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"787.683834ms","start":"2026-01-29T16:07:59.930447Z","end":"2026-01-29T16:08:00.718131Z","steps":["trace[1389515366] 'read index received' (duration: 787.490948ms)","trace[1389515366] 'applied index is now lower than readState.Index' (duration: 192.106µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:08:00.718576Z","caller":"traceutil/trace.go:171","msg":"trace[156453374] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"942.926711ms","start":"2026-01-29T16:07:59.775638Z","end":"2026-01-29T16:08:00.718565Z","steps":["trace[156453374] 'process raft request' (duration: 942.356883ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.718654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.775628Z","time spent":"942.972252ms","remote":"127.0.0.1:54392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T16:08:00.718856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.402637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:00.718884Z","caller":"traceutil/trace.go:171","msg":"trace[328043843] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:572; }","duration":"788.450578ms","start":"2026-01-29T16:07:59.930424Z","end":"2026-01-29T16:08:00.718875Z","steps":["trace[328043843] 'agreement among raft nodes before linearized reading' (duration: 788.403837ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.722228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.930412Z","time spent":"791.802014ms","remote":"127.0.0.1:45520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-01-29T16:08:00.722634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.764622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:00.722714Z","caller":"traceutil/trace.go:171","msg":"trace[1828435307] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:572; }","duration":"792.206187ms","start":"2026-01-29T16:07:59.930475Z","end":"2026-01-29T16:08:00.722682Z","steps":["trace[1828435307] 'agreement among raft nodes before linearized reading' (duration: 792.104844ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.722788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.930472Z","time spent":"792.3049ms","remote":"127.0.0.1:45520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-01-29T16:08:00.728945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.614271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:00.729041Z","caller":"traceutil/trace.go:171","msg":"trace[2124368604] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:572; }","duration":"780.709995ms","start":"2026-01-29T16:07:59.948308Z","end":"2026-01-29T16:08:00.729018Z","steps":["trace[2124368604] 'agreement among raft nodes before linearized reading' (duration: 780.592742ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.72908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.948306Z","time spent":"780.767227ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-01-29T16:08:00.729365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.055037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:00.729403Z","caller":"traceutil/trace.go:171","msg":"trace[1144859475] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:572; }","duration":"781.094478ms","start":"2026-01-29T16:07:59.948303Z","end":"2026-01-29T16:08:00.729397Z","steps":["trace[1144859475] 'agreement among raft nodes before linearized reading' (duration: 781.032096ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.729503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.9483Z","time spent":"781.195081ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-01-29T16:08:00.729625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.341116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:00.729697Z","caller":"traceutil/trace.go:171","msg":"trace[90672602] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:572; }","duration":"781.413138ms","start":"2026-01-29T16:07:59.948276Z","end":"2026-01-29T16:08:00.729689Z","steps":["trace[90672602] 'agreement among raft nodes before linearized reading' (duration: 781.329436ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.7298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.948263Z","time spent":"781.529422ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-01-29T16:08:00.729916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.852357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:00.729968Z","caller":"traceutil/trace.go:171","msg":"trace[1747459000] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:572; }","duration":"788.909909ms","start":"2026-01-29T16:07:59.941052Z","end":"2026-01-29T16:08:00.729962Z","steps":["trace[1747459000] 'agreement among raft nodes before linearized reading' (duration: 788.847547ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.729999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.94105Z","time spent":"788.94355ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-01-29T16:08:00.73016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.108105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:00.730199Z","caller":"traceutil/trace.go:171","msg":"trace[698671340] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:572; }","duration":"789.145756ms","start":"2026-01-29T16:07:59.941046Z","end":"2026-01-29T16:08:00.730192Z","steps":["trace[698671340] 'agreement among raft nodes before linearized reading' (duration: 789.084744ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.730292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.941044Z","time spent":"789.240499ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-01-29T16:08:00.730419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.411505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:00.730453Z","caller":"traceutil/trace.go:171","msg":"trace[1952706548] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:572; }","duration":"789.457056ms","start":"2026-01-29T16:07:59.94099Z","end":"2026-01-29T16:08:00.730447Z","steps":["trace[1952706548] 'agreement among raft nodes before linearized reading' (duration: 789.412835ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.7305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.940983Z","time spent":"789.511298ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-01-29T16:08:00.730686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.386945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:00.730747Z","caller":"traceutil/trace.go:171","msg":"trace[1058556251] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:572; }","duration":"799.456506ms","start":"2026-01-29T16:07:59.931283Z","end":"2026-01-29T16:08:00.730739Z","steps":["trace[1058556251] 'agreement among raft nodes before linearized reading' (duration: 799.383104ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.730811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.93128Z","time spent":"799.500128ms","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-01-29T16:08:00.73101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.727806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:00.731061Z","caller":"traceutil/trace.go:171","msg":"trace[757294531] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:572; }","duration":"799.777847ms","start":"2026-01-29T16:07:59.931266Z","end":"2026-01-29T16:08:00.731044Z","steps":["trace[757294531] 'agreement among raft nodes before linearized reading' (duration: 799.725405ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.73109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.931228Z","time spent":"799.855219ms","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-01-29T16:08:00.731243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.019994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:00.731281Z","caller":"traceutil/trace.go:171","msg":"trace[1951984972] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:572; }","duration":"800.066086ms","start":"2026-01-29T16:07:59.931209Z","end":"2026-01-29T16:08:00.731275Z","steps":["trace[1951984972] 'agreement among raft nodes before linearized reading' (duration: 800.017235ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.731326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.931204Z","time spent":"800.114848ms","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-01-29T16:08:00.731476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.614063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:00.731513Z","caller":"traceutil/trace.go:171","msg":"trace[869280593] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:572; }","duration":"800.658355ms","start":"2026-01-29T16:07:59.930848Z","end":"2026-01-29T16:08:00.731507Z","steps":["trace[869280593] 'agreement among raft nodes before linearized reading' (duration: 800.609553ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:00.731557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:07:59.930843Z","time spent":"800.706996ms","remote":"127.0.0.1:45520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-01-29T16:08:01.894392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530062541023725,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T16:08:02.395309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530062541023725,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T16:08:02.896189Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530062541023725,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T16:08:03.334825Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.099438173s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T16:08:03.335197Z","caller":"traceutil/trace.go:171","msg":"trace[1163768593] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"2.099852216s","start":"2026-01-29T16:08:01.235321Z","end":"2026-01-29T16:08:03.335173Z","steps":["trace[1163768593] 'process raft request' (duration: 2.099710992s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:03.335355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:08:01.235312Z","time spent":"2.099969621s","remote":"127.0.0.1:54454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T16:08:03.395212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000893865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-29T16:08:03.395287Z","caller":"traceutil/trace.go:171","msg":"trace[1430597588] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000981878s","start":"2026-01-29T16:08:01.394289Z","end":"2026-01-29T16:08:03.395271Z","steps":["trace[1430597588] 'agreement among raft nodes before linearized reading' (duration: 2.000890965s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:03.39534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:08:01.394242Z","time spent":"2.001091571s","remote":"127.0.0.1:54176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T16:08:03.672439Z","caller":"traceutil/trace.go:171","msg":"trace[1391511096] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"2.278107174s","start":"2026-01-29T16:08:01.394294Z","end":"2026-01-29T16:08:03.672401Z","steps":["trace[1391511096] 'read index received' (duration: 1.941516049s)","trace[1391511096] 'applied index is now lower than readState.Index' (duration: 336.588335ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:08:03.674479Z","caller":"traceutil/trace.go:171","msg":"trace[257599993] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"2.439019861s","start":"2026-01-29T16:08:01.235447Z","end":"2026-01-29T16:08:03.674467Z","steps":["trace[257599993] 'process raft request' (duration: 2.434726798s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:03.674633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:08:01.235442Z","time spent":"2.439073783s","remote":"127.0.0.1:54454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T16:08:03.675132Z","caller":"traceutil/trace.go:171","msg":"trace[243602018] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"768.501366ms","start":"2026-01-29T16:08:02.906621Z","end":"2026-01-29T16:08:03.675123Z","steps":["trace[243602018] 'process raft request' (duration: 765.685139ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:03.676719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:08:02.906602Z","time spent":"770.087284ms","remote":"127.0.0.1:54454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T16:08:03.675548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.179259375s","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-01-29T16:08:03.67689Z","caller":"traceutil/trace.go:171","msg":"trace[1365099141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:575; }","duration":"2.180614688s","start":"2026-01-29T16:08:01.496253Z","end":"2026-01-29T16:08:03.676868Z","steps":["trace[1365099141] 'agreement among raft nodes before linearized reading' (duration: 2.179202134s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:03.676918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:08:01.496227Z","time spent":"2.180685099s","remote":"127.0.0.1:54454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T16:08:03.675586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.968634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:08:03.67697Z","caller":"traceutil/trace.go:171","msg":"trace[1439809106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"281.371528ms","start":"2026-01-29T16:08:03.395594Z","end":"2026-01-29T16:08:03.676966Z","steps":["trace[1439809106] 'agreement among raft nodes before linearized reading' (duration: 279.973314ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:03.675637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.067413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-29T16:08:03.677064Z","caller":"traceutil/trace.go:171","msg":"trace[217368284] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:575; }","duration":"446.536298ms","start":"2026-01-29T16:08:03.230523Z","end":"2026-01-29T16:08:03.677059Z","steps":["trace[217368284] 'agreement among raft nodes before linearized reading' (duration: 445.073883ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:03.677083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:08:03.230495Z","time spent":"446.58424ms","remote":"127.0.0.1:54224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-01-29T16:08:03.675689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.164041417s","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-01-29T16:08:03.677178Z","caller":"traceutil/trace.go:171","msg":"trace[1251732994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"2.165539483s","start":"2026-01-29T16:08:01.511634Z","end":"2026-01-29T16:08:03.677174Z","steps":["trace[1251732994] 'agreement among raft nodes before linearized reading' (duration: 2.164011496s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:03.677199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:08:01.511629Z","time spent":"2.165564904s","remote":"127.0.0.1:54454","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-01-29T16:08:03.675729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.164414798s","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-01-29T16:08:03.677287Z","caller":"traceutil/trace.go:171","msg":"trace[410794753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"2.165994887s","start":"2026-01-29T16:08:01.511287Z","end":"2026-01-29T16:08:03.677282Z","steps":["trace[410794753] 'agreement among raft nodes before linearized reading' (duration: 2.164411038s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:08:03.677301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:08:01.511278Z","time spent":"2.166019228s","remote":"127.0.0.1:54454","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-01-29T16:09:22.310035Z","caller":"traceutil/trace.go:171","msg":"trace[1820394752] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"182.02315ms","start":"2026-01-29T16:09:22.127994Z","end":"2026-01-29T16:09:22.310017Z","steps":["trace[1820394752] 'read index received' (duration: 181.874098ms)","trace[1820394752] 'applied index is now lower than readState.Index' (duration: 148.372µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:09:22.310649Z","caller":"traceutil/trace.go:171","msg":"trace[975256033] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"235.506913ms","start":"2026-01-29T16:09:22.075132Z","end":"2026-01-29T16:09:22.310639Z","steps":["trace[975256033] 'process raft request' (duration: 234.78405ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:09:22.310928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.35614ms","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-01-29T16:09:22.311131Z","caller":"traceutil/trace.go:171","msg":"trace[904440116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:938; }","duration":"176.584963ms","start":"2026-01-29T16:09:22.134525Z","end":"2026-01-29T16:09:22.31111Z","steps":["trace[904440116] 'agreement among raft nodes before linearized reading' (duration: 176.137516ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:09:22.311524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.480804ms","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-01-29T16:09:22.311569Z","caller":"traceutil/trace.go:171","msg":"trace[1855424476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"183.585375ms","start":"2026-01-29T16:09:22.127975Z","end":"2026-01-29T16:09:22.31156Z","steps":["trace[1855424476] 'agreement among raft nodes before linearized reading' (duration: 183.400992ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:09:22.311757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.263732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T16:09:22.311781Z","caller":"traceutil/trace.go:171","msg":"trace[1906051268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:938; }","duration":"153.310732ms","start":"2026-01-29T16:09:22.158465Z","end":"2026-01-29T16:09:22.311775Z","steps":["trace[1906051268] 'agreement among raft nodes before linearized reading' (duration: 153.18089ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:09:30.860151Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530062541024668,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T16:09:31.011336Z","caller":"traceutil/trace.go:171","msg":"trace[414983803] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"656.822945ms","start":"2026-01-29T16:09:30.354267Z","end":"2026-01-29T16:09:31.011089Z","steps":["trace[414983803] 'process raft request' (duration: 656.659803ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:09:31.01149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:09:30.354254Z","time spent":"657.14532ms","remote":"127.0.0.1:54454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T16:09:31.013456Z","caller":"traceutil/trace.go:171","msg":"trace[178007322] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1006; }","duration":"654.135234ms","start":"2026-01-29T16:09:30.359302Z","end":"2026-01-29T16:09:31.013437Z","steps":["trace[178007322] 'read index received' (duration: 651.653796ms)","trace[178007322] 'applied index is now lower than readState.Index' (duration: 2.480888ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:09:31.013636Z","caller":"traceutil/trace.go:171","msg":"trace[1524879894] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"656.739574ms","start":"2026-01-29T16:09:30.356883Z","end":"2026-01-29T16:09:31.013622Z","steps":["trace[1524879894] 'process raft request' (duration: 656.428199ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:09:31.01381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:09:30.356873Z","time spent":"656.819235ms","remote":"127.0.0.1:54454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T16:09:31.013946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.621181ms","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-01-29T16:09:31.014117Z","caller":"traceutil/trace.go:171","msg":"trace[492730070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:969; }","duration":"654.819994ms","start":"2026-01-29T16:09:30.359284Z","end":"2026-01-29T16:09:31.014104Z","steps":["trace[492730070] 'agreement among raft nodes before linearized reading' (duration: 654.359527ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:09:31.014197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:09:30.359258Z","time spent":"654.929636ms","remote":"127.0.0.1:54454","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-01-29T16:09:31.014384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.323317ms","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-01-29T16:09:31.014459Z","caller":"traceutil/trace.go:171","msg":"trace[892272844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"654.409699ms","start":"2026-01-29T16:09:30.360042Z","end":"2026-01-29T16:09:31.014451Z","steps":["trace[892272844] 'agreement among raft nodes before linearized reading' (duration: 654.300336ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:09:31.014538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:09:30.360034Z","time spent":"654.49593ms","remote":"127.0.0.1:54454","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-01-29T16:09:31.014811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.8174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T16:09:31.014914Z","caller":"traceutil/trace.go:171","msg":"trace[862176388] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:969; }","duration":"264.950102ms","start":"2026-01-29T16:09:30.749956Z","end":"2026-01-29T16:09:31.014906Z","steps":["trace[862176388] 'agreement among raft nodes before linearized reading' (duration: 264.82758ms)"],"step_count":1}