{"level":"warn","ts":"2026-02-05T05:23:41.559382Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T05:23:41.559517Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155: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.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155: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-02-05T05:23:41.559669Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T05:23:41.559689Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-02-05T05:23:41.559855Z","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-02-05T05:23:41.561387Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-02-05T05:23:41.561539Z","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.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155: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-02-05T05:23:41.566342Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.479879ms"} {"level":"info","ts":"2026-02-05T05:23:41.574523Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-02-05T05:23:41.574711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T05:23:41.574743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-02-05T05:23:41.57543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T05:23:41.575813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-02-05T05:23:41.575992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-02-05T05:23:41.593225Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T05:23:41.597621Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T05:23:41.60028Z","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-02-05T05:23:41.604952Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T05:23:41.605346Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T05:23:41.605771Z","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-02-05T05:23:41.605802Z","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-02-05T05:23:41.605809Z","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-02-05T05:23:41.606133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-02-05T05:23:41.607084Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-02-05T05:23:41.622097Z","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-02-05T05:23:41.622264Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T05:23:41.622289Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T05:23:41.622389Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-02-05T05:23:41.622403Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-02-05T05:23:42.377293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T05:23:42.377386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T05:23:42.37741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-02-05T05:23:42.377434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-02-05T05:23:42.377468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-02-05T05:23:42.377503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-02-05T05:23:42.377524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-02-05T05:23:42.378974Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:23:42.383401Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:23:42.383473Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:23:42.383503Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:23:42.383554Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T05:23:42.383652Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:23:42.389048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T05:23:42.389197Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T05:23:42.38925Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T05:23:42.389089Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:23:42.423313Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-02-05T05:23:52.45465Z","caller":"traceutil/trace.go:171","msg":"trace[610477143] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"197.133875ms","start":"2026-02-05T05:23:52.257494Z","end":"2026-02-05T05:23:52.454628Z","steps":["trace[610477143] 'read index received' (duration: 196.874123ms)","trace[610477143] 'applied index is now lower than readState.Index' (duration: 258.791µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:23:52.454955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.395306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-05T05:23:52.45502Z","caller":"traceutil/trace.go:171","msg":"trace[1531617567] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:350; }","duration":"197.562542ms","start":"2026-02-05T05:23:52.257445Z","end":"2026-02-05T05:23:52.455008Z","steps":["trace[1531617567] 'agreement among raft nodes before linearized reading' (duration: 197.402308ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:23:52.455394Z","caller":"traceutil/trace.go:171","msg":"trace[22349872] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"214.890589ms","start":"2026-02-05T05:23:52.240492Z","end":"2026-02-05T05:23:52.455382Z","steps":["trace[22349872] 'process raft request' (duration: 213.927269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:52.455941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.257174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-05T05:23:52.455983Z","caller":"traceutil/trace.go:171","msg":"trace[1826141396] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:350; }","duration":"180.321771ms","start":"2026-02-05T05:23:52.275652Z","end":"2026-02-05T05:23:52.455974Z","steps":["trace[1826141396] 'agreement among raft nodes before linearized reading' (duration: 180.245824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:52.457038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.271318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-05T05:23:52.457081Z","caller":"traceutil/trace.go:171","msg":"trace[1511987829] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:350; }","duration":"131.314551ms","start":"2026-02-05T05:23:52.325755Z","end":"2026-02-05T05:23:52.45707Z","steps":["trace[1511987829] 'agreement among raft nodes before linearized reading' (duration: 131.251386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:52.467827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.586387ms","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-02-05T05:23:52.467868Z","caller":"traceutil/trace.go:171","msg":"trace[85182151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:350; }","duration":"153.653973ms","start":"2026-02-05T05:23:52.314203Z","end":"2026-02-05T05:23:52.467857Z","steps":["trace[85182151] 'agreement among raft nodes before linearized reading' (duration: 153.579047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:58.237726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.140044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:23:58.238407Z","caller":"traceutil/trace.go:171","msg":"trace[1098975046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"166.859025ms","start":"2026-02-05T05:23:58.071528Z","end":"2026-02-05T05:23:58.238387Z","steps":["trace[1098975046] 'range keys from in-memory index tree' (duration: 166.061347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:58.238631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.46175ms","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-02-05T05:23:58.238897Z","caller":"traceutil/trace.go:171","msg":"trace[862529017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"158.783978ms","start":"2026-02-05T05:23:58.079947Z","end":"2026-02-05T05:23:58.238731Z","steps":["trace[862529017] 'range keys from in-memory index tree' (duration: 158.358722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:58.23917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.102407ms","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-02-05T05:23:58.247887Z","caller":"traceutil/trace.go:171","msg":"trace[452817594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"155.018837ms","start":"2026-02-05T05:23:58.092856Z","end":"2026-02-05T05:23:58.247875Z","steps":["trace[452817594] 'range keys from in-memory index tree' (duration: 146.0138ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:23:58.595782Z","caller":"traceutil/trace.go:171","msg":"trace[1962998955] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"340.496819ms","start":"2026-02-05T05:23:58.255264Z","end":"2026-02-05T05:23:58.595761Z","steps":["trace[1962998955] 'process raft request' (duration: 340.394821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:58.596974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:23:58.255251Z","time spent":"340.596238ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:23:58.998119Z","caller":"traceutil/trace.go:171","msg":"trace[1958061354] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"481.239769ms","start":"2026-02-05T05:23:58.51683Z","end":"2026-02-05T05:23:58.99807Z","steps":["trace[1958061354] 'read index received' (duration: 79.321267ms)","trace[1958061354] 'applied index is now lower than readState.Index' (duration: 401.788981ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:23:58.999533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.673291ms","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-02-05T05:23:58.99966Z","caller":"traceutil/trace.go:171","msg":"trace[1617784360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"482.789912ms","start":"2026-02-05T05:23:58.516787Z","end":"2026-02-05T05:23:58.999577Z","steps":["trace[1617784360] 'agreement among raft nodes before linearized reading' (duration: 482.6635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:58.999731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:23:58.516763Z","time spent":"482.959157ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T05:23:59.000073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.049799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-05T05:23:59.000144Z","caller":"traceutil/trace.go:171","msg":"trace[545943825] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:418; }","duration":"457.17312ms","start":"2026-02-05T05:23:58.54296Z","end":"2026-02-05T05:23:59.000133Z","steps":["trace[545943825] 'agreement among raft nodes before linearized reading' (duration: 456.926609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:59.00021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:23:58.542941Z","time spent":"457.259807ms","remote":"127.0.0.1:48906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4056,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-05T05:23:59.002001Z","caller":"traceutil/trace.go:171","msg":"trace[456167207] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"746.523841ms","start":"2026-02-05T05:23:58.255455Z","end":"2026-02-05T05:23:59.001979Z","steps":["trace[456167207] 'process raft request' (duration: 682.968307ms)","trace[456167207] 'compare' (duration: 56.508803ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:23:59.005887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:23:58.255448Z","time spent":"746.606958ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:24:13.277305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694579259837519,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:24:13.284689Z","caller":"traceutil/trace.go:171","msg":"trace[130750002] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"507.59307ms","start":"2026-02-05T05:24:12.777077Z","end":"2026-02-05T05:24:13.28467Z","steps":["trace[130750002] 'read index received' (duration: 507.441916ms)","trace[130750002] 'applied index is now lower than readState.Index' (duration: 150.434µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:13.284884Z","caller":"traceutil/trace.go:171","msg":"trace[1253160867] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"608.206902ms","start":"2026-02-05T05:24:12.67667Z","end":"2026-02-05T05:24:13.284877Z","steps":["trace[1253160867] 'process raft request' (duration: 607.897943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:13.284954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:12.676662Z","time spent":"608.241444ms","remote":"127.0.0.1:48274","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-02-05T05:24:13.285081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.002377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-05T05:24:13.285103Z","caller":"traceutil/trace.go:171","msg":"trace[1391216336] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:513; }","duration":"508.043991ms","start":"2026-02-05T05:24:12.777051Z","end":"2026-02-05T05:24:13.285095Z","steps":["trace[1391216336] 'agreement among raft nodes before linearized reading' (duration: 508.002717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:13.28512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:12.777028Z","time spent":"508.088245ms","remote":"127.0.0.1:48194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4041,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-05T05:24:13.285216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.122009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:13.285234Z","caller":"traceutil/trace.go:171","msg":"trace[851431922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"213.173464ms","start":"2026-02-05T05:24:13.072055Z","end":"2026-02-05T05:24:13.285229Z","steps":["trace[851431922] 'agreement among raft nodes before linearized reading' (duration: 213.147611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:13.285368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.388061ms","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-02-05T05:24:13.285387Z","caller":"traceutil/trace.go:171","msg":"trace[1353464857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"200.420423ms","start":"2026-02-05T05:24:13.084961Z","end":"2026-02-05T05:24:13.285382Z","steps":["trace[1353464857] 'agreement among raft nodes before linearized reading' (duration: 200.38795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:13.285721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.81901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-05T05:24:13.287401Z","caller":"traceutil/trace.go:171","msg":"trace[1201689287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"208.462908ms","start":"2026-02-05T05:24:13.078882Z","end":"2026-02-05T05:24:13.287344Z","steps":["trace[1201689287] 'agreement among raft nodes before linearized reading' (duration: 206.810299ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:24:13.683567Z","caller":"traceutil/trace.go:171","msg":"trace[111056239] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"391.573391ms","start":"2026-02-05T05:24:13.291975Z","end":"2026-02-05T05:24:13.683549Z","steps":["trace[111056239] 'process raft request' (duration: 389.954915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:13.683669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:13.291965Z","time spent":"391.650588ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:24:13.687665Z","caller":"traceutil/trace.go:171","msg":"trace[1293449267] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"393.084868ms","start":"2026-02-05T05:24:13.294554Z","end":"2026-02-05T05:24:13.687639Z","steps":["trace[1293449267] 'process raft request' (duration: 392.987769ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:24:13.687735Z","caller":"traceutil/trace.go:171","msg":"trace[1466812043] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:529; }","duration":"311.88106ms","start":"2026-02-05T05:24:13.37584Z","end":"2026-02-05T05:24:13.687721Z","steps":["trace[1466812043] 'read index received' (duration: 306.099399ms)","trace[1466812043] 'applied index is now lower than readState.Index' (duration: 5.779271ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:24:13.687804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.965788ms","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-02-05T05:24:13.68783Z","caller":"traceutil/trace.go:171","msg":"trace[1696829887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"312.020952ms","start":"2026-02-05T05:24:13.375803Z","end":"2026-02-05T05:24:13.687824Z","steps":["trace[1696829887] 'agreement among raft nodes before linearized reading' (duration: 311.963577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:13.68785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:13.375792Z","time spent":"312.053865ms","remote":"127.0.0.1:48274","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-02-05T05:24:13.687893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:13.294542Z","time spent":"393.193217ms","remote":"127.0.0.1:48274","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-02-05T05:24:13.688006Z","caller":"traceutil/trace.go:171","msg":"trace[968294367] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"392.365134ms","start":"2026-02-05T05:24:13.295633Z","end":"2026-02-05T05:24:13.687998Z","steps":["trace[968294367] 'process raft request' (duration: 391.967347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:13.692057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:13.295628Z","time spent":"396.380015ms","remote":"127.0.0.1:48194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:24:17.848136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288293086s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:24:17.848271Z","caller":"traceutil/trace.go:171","msg":"trace[1252524991] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"538.371907ms","start":"2026-02-05T05:24:17.309868Z","end":"2026-02-05T05:24:17.84824Z","steps":["trace[1252524991] 'read index received' (duration: 34.153µs)","trace[1252524991] 'applied index is now lower than readState.Index' (duration: 538.336344ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:17.848398Z","caller":"traceutil/trace.go:171","msg":"trace[413993654] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.317307976s","start":"2026-02-05T05:24:16.531077Z","end":"2026-02-05T05:24:17.848385Z","steps":["trace[413993654] 'process raft request' (duration: 28.508323ms)","trace[413993654] 'compare' (duration: 1.288146643s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:24:17.848479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:16.531068Z","time spent":"1.317357731s","remote":"127.0.0.1:48212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T05:24:17.848858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.997104ms","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-02-05T05:24:17.848996Z","caller":"traceutil/trace.go:171","msg":"trace[1976378656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"539.194561ms","start":"2026-02-05T05:24:17.30979Z","end":"2026-02-05T05:24:17.848985Z","steps":["trace[1976378656] 'agreement among raft nodes before linearized reading' (duration: 538.934608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:17.849069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:17.30977Z","time spent":"539.28806ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T05:24:18.14727Z","caller":"traceutil/trace.go:171","msg":"trace[1659225067] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"298.950391ms","start":"2026-02-05T05:24:17.848296Z","end":"2026-02-05T05:24:18.147246Z","steps":["trace[1659225067] 'read index received' (duration: 232.908972ms)","trace[1659225067] 'applied index is now lower than readState.Index' (duration: 66.040508ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:18.147792Z","caller":"traceutil/trace.go:171","msg":"trace[282228344] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"366.273473ms","start":"2026-02-05T05:24:17.781503Z","end":"2026-02-05T05:24:18.147776Z","steps":["trace[282228344] 'process raft request' (duration: 299.669442ms)","trace[282228344] 'compare' (duration: 65.962581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:24:18.149012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:17.78148Z","time spent":"367.474301ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:24:18.14837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.192891ms","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-02-05T05:24:18.14934Z","caller":"traceutil/trace.go:171","msg":"trace[1287896819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"405.280981ms","start":"2026-02-05T05:24:17.744042Z","end":"2026-02-05T05:24:18.149323Z","steps":["trace[1287896819] 'agreement among raft nodes before linearized reading' (duration: 404.153028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:18.149675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:17.744022Z","time spent":"405.518713ms","remote":"127.0.0.1:48274","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-02-05T05:24:18.148835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.927993ms","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-02-05T05:24:18.150012Z","caller":"traceutil/trace.go:171","msg":"trace[714516018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"410.124492ms","start":"2026-02-05T05:24:17.739874Z","end":"2026-02-05T05:24:18.149999Z","steps":["trace[714516018] 'agreement among raft nodes before linearized reading' (duration: 408.542298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:18.15039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:17.739864Z","time spent":"410.510687ms","remote":"127.0.0.1:48274","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-02-05T05:24:18.148919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.168959ms","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-02-05T05:24:18.150619Z","caller":"traceutil/trace.go:171","msg":"trace[704439077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"447.895997ms","start":"2026-02-05T05:24:17.70271Z","end":"2026-02-05T05:24:18.150606Z","steps":["trace[704439077] 'agreement among raft nodes before linearized reading' (duration: 446.151737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:18.151323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:17.702672Z","time spent":"448.634705ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T05:24:18.221989Z","caller":"traceutil/trace.go:171","msg":"trace[372796408] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"368.598161ms","start":"2026-02-05T05:24:17.853373Z","end":"2026-02-05T05:24:18.221971Z","steps":["trace[372796408] 'process raft request' (duration: 368.179502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:18.221996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.193115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-05T05:24:18.222107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:17.853352Z","time spent":"368.69502ms","remote":"127.0.0.1:48120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1427,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T05:24:18.222488Z","caller":"traceutil/trace.go:171","msg":"trace[20943315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"151.650826ms","start":"2026-02-05T05:24:18.070695Z","end":"2026-02-05T05:24:18.222346Z","steps":["trace[20943315] 'agreement among raft nodes before linearized reading' (duration: 151.168291ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:24:18.222976Z","caller":"traceutil/trace.go:171","msg":"trace[1447355198] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"367.7101ms","start":"2026-02-05T05:24:17.855247Z","end":"2026-02-05T05:24:18.222958Z","steps":["trace[1447355198] 'process raft request' (duration: 366.483877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:18.223137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:17.855235Z","time spent":"367.808038ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:24:18.279653Z","caller":"traceutil/trace.go:171","msg":"trace[1028116309] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"122.125258ms","start":"2026-02-05T05:24:18.157501Z","end":"2026-02-05T05:24:18.279626Z","steps":["trace[1028116309] 'process raft request' (duration: 121.158469ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:24:18.280466Z","caller":"traceutil/trace.go:171","msg":"trace[1295652385] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"122.977556ms","start":"2026-02-05T05:24:18.15747Z","end":"2026-02-05T05:24:18.280448Z","steps":["trace[1295652385] 'process raft request' (duration: 120.897456ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:24:18.281824Z","caller":"traceutil/trace.go:171","msg":"trace[1695043290] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"124.24108ms","start":"2026-02-05T05:24:18.157568Z","end":"2026-02-05T05:24:18.281809Z","steps":["trace[1695043290] 'process raft request' (duration: 121.158649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:22.178861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.589012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:22.17898Z","caller":"traceutil/trace.go:171","msg":"trace[782992013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"109.745417ms","start":"2026-02-05T05:24:22.069215Z","end":"2026-02-05T05:24:22.178961Z","steps":["trace[782992013] 'range keys from in-memory index tree' (duration: 109.533998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:22.179513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.714795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j45jf\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-05T05:24:22.179549Z","caller":"traceutil/trace.go:171","msg":"trace[234649785] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j45jf; range_end:; response_count:1; response_revision:557; }","duration":"833.7757ms","start":"2026-02-05T05:24:21.345763Z","end":"2026-02-05T05:24:22.179538Z","steps":["trace[234649785] 'range keys from in-memory index tree' (duration: 833.574682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:22.179577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:21.345749Z","time spent":"833.821024ms","remote":"127.0.0.1:48196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-j45jf\" "} {"level":"warn","ts":"2026-02-05T05:24:22.738934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694579259837614,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:24:23.031177Z","caller":"traceutil/trace.go:171","msg":"trace[507441249] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"792.75025ms","start":"2026-02-05T05:24:22.238413Z","end":"2026-02-05T05:24:23.031163Z","steps":["trace[507441249] 'read index received' (duration: 792.596405ms)","trace[507441249] 'applied index is now lower than readState.Index' (duration: 153.304µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:23.031417Z","caller":"traceutil/trace.go:171","msg":"trace[807685458] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"839.033961ms","start":"2026-02-05T05:24:22.192377Z","end":"2026-02-05T05:24:23.031411Z","steps":["trace[807685458] 'process raft request' (duration: 838.681599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:23.031481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:22.192361Z","time spent":"839.075675ms","remote":"127.0.0.1:48196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:24:23.031578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.167097ms","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-02-05T05:24:23.031634Z","caller":"traceutil/trace.go:171","msg":"trace[64967271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"731.246624ms","start":"2026-02-05T05:24:22.300371Z","end":"2026-02-05T05:24:23.031618Z","steps":["trace[64967271] 'agreement among raft nodes before linearized reading' (duration: 731.098251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:23.031687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:22.300315Z","time spent":"731.364695ms","remote":"127.0.0.1:48274","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-02-05T05:24:23.031769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.357447ms","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-02-05T05:24:23.031808Z","caller":"traceutil/trace.go:171","msg":"trace[1634000422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"793.449285ms","start":"2026-02-05T05:24:22.238348Z","end":"2026-02-05T05:24:23.031798Z","steps":["trace[1634000422] 'agreement among raft nodes before linearized reading' (duration: 793.362886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:23.031831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:22.238327Z","time spent":"793.499039ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:24:23.031691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.334613ms","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-02-05T05:24:23.031889Z","caller":"traceutil/trace.go:171","msg":"trace[1596432618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"696.562695ms","start":"2026-02-05T05:24:22.33532Z","end":"2026-02-05T05:24:23.031883Z","steps":["trace[1596432618] 'agreement among raft nodes before linearized reading' (duration: 696.338953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:23.031918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:22.335292Z","time spent":"696.62193ms","remote":"127.0.0.1:48274","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-02-05T05:24:23.031996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.735006ms","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-02-05T05:24:23.032041Z","caller":"traceutil/trace.go:171","msg":"trace[1853030740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"716.803382ms","start":"2026-02-05T05:24:22.315227Z","end":"2026-02-05T05:24:23.03203Z","steps":["trace[1853030740] 'agreement among raft nodes before linearized reading' (duration: 716.731255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:23.03206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:22.315213Z","time spent":"716.842405ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T05:24:23.169499Z","caller":"traceutil/trace.go:171","msg":"trace[1871736491] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:576; }","duration":"100.653868ms","start":"2026-02-05T05:24:23.068832Z","end":"2026-02-05T05:24:23.169486Z","steps":["trace[1871736491] 'read index received' (duration: 81.675412ms)","trace[1871736491] 'applied index is now lower than readState.Index' (duration: 18.977966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:24:23.169584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.738576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:23.169601Z","caller":"traceutil/trace.go:171","msg":"trace[823877610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"100.799971ms","start":"2026-02-05T05:24:23.068795Z","end":"2026-02-05T05:24:23.169595Z","steps":["trace[823877610] 'agreement among raft nodes before linearized reading' (duration: 100.740975ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:24:23.169753Z","caller":"traceutil/trace.go:171","msg":"trace[154789615] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"133.263013ms","start":"2026-02-05T05:24:23.036483Z","end":"2026-02-05T05:24:23.169746Z","steps":["trace[154789615] 'process raft request' (duration: 114.016133ms)","trace[154789615] 'compare' (duration: 18.761065ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:23.169822Z","caller":"traceutil/trace.go:171","msg":"trace[934552467] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"127.489329ms","start":"2026-02-05T05:24:23.042327Z","end":"2026-02-05T05:24:23.169817Z","steps":["trace[934552467] 'process raft request' (duration: 127.108654ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:24:23.169878Z","caller":"traceutil/trace.go:171","msg":"trace[1095384826] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"127.471057ms","start":"2026-02-05T05:24:23.042402Z","end":"2026-02-05T05:24:23.169874Z","steps":["trace[1095384826] 'process raft request' (duration: 127.052979ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:24:23.169931Z","caller":"traceutil/trace.go:171","msg":"trace[1201684771] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"127.472528ms","start":"2026-02-05T05:24:23.042453Z","end":"2026-02-05T05:24:23.169926Z","steps":["trace[1201684771] 'process raft request' (duration: 127.017357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:25.595642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52664484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:25.595691Z","caller":"traceutil/trace.go:171","msg":"trace[1866166042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"1.526726167s","start":"2026-02-05T05:24:24.068954Z","end":"2026-02-05T05:24:25.59568Z","steps":["trace[1866166042] 'range keys from in-memory index tree' (duration: 1.526589355s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:25.595711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:24.068929Z","time spent":"1.526778172s","remote":"127.0.0.1:47980","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-02-05T05:24:25.619708Z","caller":"traceutil/trace.go:171","msg":"trace[2073125674] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"445.272541ms","start":"2026-02-05T05:24:25.174413Z","end":"2026-02-05T05:24:25.619686Z","steps":["trace[2073125674] 'read index received' (duration: 445.117506ms)","trace[2073125674] 'applied index is now lower than readState.Index' (duration: 154.355µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:25.619934Z","caller":"traceutil/trace.go:171","msg":"trace[1706937584] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"531.335572ms","start":"2026-02-05T05:24:25.088591Z","end":"2026-02-05T05:24:25.619927Z","steps":["trace[1706937584] 'process raft request' (duration: 530.98901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:25.620008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:25.088567Z","time spent":"531.386486ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:24:25.620223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.80816ms","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-02-05T05:24:25.620245Z","caller":"traceutil/trace.go:171","msg":"trace[692390854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:563; }","duration":"445.870176ms","start":"2026-02-05T05:24:25.174368Z","end":"2026-02-05T05:24:25.620238Z","steps":["trace[692390854] 'agreement among raft nodes before linearized reading' (duration: 445.786848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:25.620278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:25.174356Z","time spent":"445.91723ms","remote":"127.0.0.1:48274","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-02-05T05:24:25.620408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.928151ms","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-02-05T05:24:25.620431Z","caller":"traceutil/trace.go:171","msg":"trace[1162107532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"445.968595ms","start":"2026-02-05T05:24:25.174457Z","end":"2026-02-05T05:24:25.620426Z","steps":["trace[1162107532] 'agreement among raft nodes before linearized reading' (duration: 445.908559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:25.620452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:25.17422Z","time spent":"446.227598ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T05:24:25.620602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.636157ms","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-02-05T05:24:25.620625Z","caller":"traceutil/trace.go:171","msg":"trace[432788929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:563; }","duration":"443.685423ms","start":"2026-02-05T05:24:25.176934Z","end":"2026-02-05T05:24:25.620619Z","steps":["trace[432788929] 'agreement among raft nodes before linearized reading' (duration: 443.626307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:25.620641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:25.176918Z","time spent":"443.719495ms","remote":"127.0.0.1:48274","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-02-05T05:24:25.620686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.279175ms","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-02-05T05:24:25.620729Z","caller":"traceutil/trace.go:171","msg":"trace[668362408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"443.362183ms","start":"2026-02-05T05:24:25.177356Z","end":"2026-02-05T05:24:25.620718Z","steps":["trace[668362408] 'agreement among raft nodes before linearized reading' (duration: 443.190587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:25.620755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:25.177347Z","time spent":"443.402807ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:24:25.62098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.201214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T05:24:25.620999Z","caller":"traceutil/trace.go:171","msg":"trace[58859174] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:563; }","duration":"153.254999ms","start":"2026-02-05T05:24:25.467738Z","end":"2026-02-05T05:24:25.620993Z","steps":["trace[58859174] 'agreement among raft nodes before linearized reading' (duration: 153.198074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.015254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.07874ms","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-02-05T05:24:28.015304Z","caller":"traceutil/trace.go:171","msg":"trace[1951905969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:580; }","duration":"386.155288ms","start":"2026-02-05T05:24:27.629137Z","end":"2026-02-05T05:24:28.015292Z","steps":["trace[1951905969] 'range keys from in-memory index tree' (duration: 385.985712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.015327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.629126Z","time spent":"386.196741ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:24:28.015422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.053872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:28.015436Z","caller":"traceutil/trace.go:171","msg":"trace[1485194523] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:580; }","duration":"381.078194ms","start":"2026-02-05T05:24:27.634353Z","end":"2026-02-05T05:24:28.015431Z","steps":["trace[1485194523] 'range keys from in-memory index tree' (duration: 381.02981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.01545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.634345Z","time spent":"381.099697ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:24:28.015516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.518554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:28.015528Z","caller":"traceutil/trace.go:171","msg":"trace[31380089] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:580; }","duration":"374.529925ms","start":"2026-02-05T05:24:27.640994Z","end":"2026-02-05T05:24:28.015524Z","steps":["trace[31380089] 'range keys from in-memory index tree' (duration: 374.499842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.01554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.640984Z","time spent":"374.553137ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:24:28.015595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.628944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:28.015608Z","caller":"traceutil/trace.go:171","msg":"trace[974271351] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:580; }","duration":"374.653937ms","start":"2026-02-05T05:24:27.640951Z","end":"2026-02-05T05:24:28.015605Z","steps":["trace[974271351] 'range keys from in-memory index tree' (duration: 374.569728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.01562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.640944Z","time spent":"374.672778ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:24:28.015675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.476438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:28.015687Z","caller":"traceutil/trace.go:171","msg":"trace[1769583973] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:580; }","duration":"367.495189ms","start":"2026-02-05T05:24:27.648189Z","end":"2026-02-05T05:24:28.015684Z","steps":["trace[1769583973] 'range keys from in-memory index tree' (duration: 367.461917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.015699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.648186Z","time spent":"367.509651ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:24:28.015753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.584518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:28.015766Z","caller":"traceutil/trace.go:171","msg":"trace[485934122] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:580; }","duration":"367.605219ms","start":"2026-02-05T05:24:27.648156Z","end":"2026-02-05T05:24:28.015761Z","steps":["trace[485934122] 'range keys from in-memory index tree' (duration: 367.527673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.015778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.648151Z","time spent":"367.623191ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:24:28.01583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.926752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:28.015842Z","caller":"traceutil/trace.go:171","msg":"trace[1384666708] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:580; }","duration":"365.947614ms","start":"2026-02-05T05:24:27.649891Z","end":"2026-02-05T05:24:28.015839Z","steps":["trace[1384666708] 'range keys from in-memory index tree' (duration: 365.908251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.015853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.649887Z","time spent":"365.963986ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:24:28.015933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.281681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-05T05:24:28.015947Z","caller":"traceutil/trace.go:171","msg":"trace[572705717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"358.305543ms","start":"2026-02-05T05:24:27.657638Z","end":"2026-02-05T05:24:28.015943Z","steps":["trace[572705717] 'range keys from in-memory index tree' (duration: 358.237366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.015958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.657633Z","time spent":"358.322304ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T05:24:28.016032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.000805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T05:24:28.016043Z","caller":"traceutil/trace.go:171","msg":"trace[464511530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"358.021357ms","start":"2026-02-05T05:24:27.658018Z","end":"2026-02-05T05:24:28.01604Z","steps":["trace[464511530] 'range keys from in-memory index tree' (duration: 357.967361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.016056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.658009Z","time spent":"358.043828ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:24:28.016116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.505888ms","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-02-05T05:24:28.016127Z","caller":"traceutil/trace.go:171","msg":"trace[1155241924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"357.52535ms","start":"2026-02-05T05:24:27.658598Z","end":"2026-02-05T05:24:28.016123Z","steps":["trace[1155241924] 'range keys from in-memory index tree' (duration: 357.462884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.016139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.658593Z","time spent":"357.543301ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:24:28.016206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.221501ms","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-02-05T05:24:28.016217Z","caller":"traceutil/trace.go:171","msg":"trace[888801757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"357.346853ms","start":"2026-02-05T05:24:27.658867Z","end":"2026-02-05T05:24:28.016214Z","steps":["trace[888801757] 'range keys from in-memory index tree' (duration: 357.187139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.016228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.658863Z","time spent":"357.362164ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:24:28.016277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.243654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:28.016288Z","caller":"traceutil/trace.go:171","msg":"trace[22021620] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:580; }","duration":"357.266546ms","start":"2026-02-05T05:24:27.659018Z","end":"2026-02-05T05:24:28.016285Z","steps":["trace[22021620] 'range keys from in-memory index tree' (duration: 357.215821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.0163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.659015Z","time spent":"357.281897ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:24:28.016352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.86559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:28.016363Z","caller":"traceutil/trace.go:171","msg":"trace[132450145] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:580; }","duration":"347.885571ms","start":"2026-02-05T05:24:27.668474Z","end":"2026-02-05T05:24:28.016359Z","steps":["trace[132450145] 'range keys from in-memory index tree' (duration: 347.850747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.016375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.668471Z","time spent":"347.901263ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:24:28.016432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.976219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:28.016444Z","caller":"traceutil/trace.go:171","msg":"trace[55135821] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:580; }","duration":"347.98828ms","start":"2026-02-05T05:24:27.668452Z","end":"2026-02-05T05:24:28.01644Z","steps":["trace[55135821] 'range keys from in-memory index tree' (duration: 347.923075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.016455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.668438Z","time spent":"348.014273ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:24:28.016508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.61386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:24:28.01652Z","caller":"traceutil/trace.go:171","msg":"trace[1306274589] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:580; }","duration":"323.646833ms","start":"2026-02-05T05:24:27.692869Z","end":"2026-02-05T05:24:28.016516Z","steps":["trace[1306274589] 'range keys from in-memory index tree' (duration: 323.582077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:28.016532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:27.692857Z","time spent":"323.671215ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:24:28.016584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.421539ms","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-02-05T05:24:28.016596Z","caller":"traceutil/trace.go:171","msg":"trace[770788797] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:580; }","duration":"184.465343ms","start":"2026-02-05T05:24:27.832127Z","end":"2026-02-05T05:24:28.016592Z","steps":["trace[770788797] 'range keys from in-memory index tree' (duration: 184.373584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:30.737139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.455424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-05T05:24:30.737193Z","caller":"traceutil/trace.go:171","msg":"trace[1136736926] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:641; }","duration":"104.558963ms","start":"2026-02-05T05:24:30.632623Z","end":"2026-02-05T05:24:30.737182Z","steps":["trace[1136736926] 'range keys from in-memory index tree' (duration: 104.30203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:50.495802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.545869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:24:50.496116Z","caller":"traceutil/trace.go:171","msg":"trace[283635570] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"296.714203ms","start":"2026-02-05T05:24:50.199385Z","end":"2026-02-05T05:24:50.496099Z","steps":["trace[283635570] 'read index received' (duration: 35.829853ms)","trace[283635570] 'applied index is now lower than readState.Index' (duration: 260.88313ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:50.497208Z","caller":"traceutil/trace.go:171","msg":"trace[1807786649] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"300.606532ms","start":"2026-02-05T05:24:50.196581Z","end":"2026-02-05T05:24:50.497187Z","steps":["trace[1807786649] 'process raft request' (duration: 38.599893ms)","trace[1807786649] 'compare' (duration: 260.448234ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:24:50.497456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:24:50.196566Z","time spent":"300.847289ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:24:50.497952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.562302ms","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-02-05T05:24:50.49815Z","caller":"traceutil/trace.go:171","msg":"trace[212927460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"298.756523ms","start":"2026-02-05T05:24:50.199353Z","end":"2026-02-05T05:24:50.498109Z","steps":["trace[212927460] 'agreement among raft nodes before linearized reading' (duration: 298.507525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:50.498643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.705961ms","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-02-05T05:24:50.498836Z","caller":"traceutil/trace.go:171","msg":"trace[1422125468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:790; }","duration":"294.884522ms","start":"2026-02-05T05:24:50.203912Z","end":"2026-02-05T05:24:50.498797Z","steps":["trace[1422125468] 'agreement among raft nodes before linearized reading' (duration: 294.652125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:50.499499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.964517ms","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-02-05T05:24:50.499741Z","caller":"traceutil/trace.go:171","msg":"trace[303084838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"295.215634ms","start":"2026-02-05T05:24:50.204512Z","end":"2026-02-05T05:24:50.499728Z","steps":["trace[303084838] 'agreement among raft nodes before linearized reading' (duration: 294.91439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:50.710413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.036268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:24:50.710547Z","caller":"traceutil/trace.go:171","msg":"trace[1476914560] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"201.511561ms","start":"2026-02-05T05:24:50.509013Z","end":"2026-02-05T05:24:50.710525Z","steps":["trace[1476914560] 'process raft request' (duration: 201.490713ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:24:50.710745Z","caller":"traceutil/trace.go:171","msg":"trace[621268295] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"205.307425ms","start":"2026-02-05T05:24:50.50543Z","end":"2026-02-05T05:24:50.710737Z","steps":["trace[621268295] 'process raft request' (duration: 94.896407ms)","trace[621268295] 'compare' (duration: 109.957373ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:50.710839Z","caller":"traceutil/trace.go:171","msg":"trace[705272472] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"203.699884ms","start":"2026-02-05T05:24:50.507133Z","end":"2026-02-05T05:24:50.710833Z","steps":["trace[705272472] 'process raft request' (duration: 203.341542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:50.900179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.335153ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-05T05:24:52.383939Z","caller":"traceutil/trace.go:171","msg":"trace[704347942] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"205.454782ms","start":"2026-02-05T05:24:52.178468Z","end":"2026-02-05T05:24:52.383923Z","steps":["trace[704347942] 'read index received' (duration: 205.288379ms)","trace[704347942] 'applied index is now lower than readState.Index' (duration: 165.793µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:52.384163Z","caller":"traceutil/trace.go:171","msg":"trace[1289905543] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"212.546017ms","start":"2026-02-05T05:24:52.171611Z","end":"2026-02-05T05:24:52.384157Z","steps":["trace[1289905543] 'process raft request' (duration: 212.208233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:52.384282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.799744ms","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-02-05T05:24:52.384299Z","caller":"traceutil/trace.go:171","msg":"trace[910358854] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:794; }","duration":"205.862031ms","start":"2026-02-05T05:24:52.178431Z","end":"2026-02-05T05:24:52.384293Z","steps":["trace[910358854] 'agreement among raft nodes before linearized reading' (duration: 205.819123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:52.38438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.903639ms","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-02-05T05:24:52.384395Z","caller":"traceutil/trace.go:171","msg":"trace[1865149705] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:794; }","duration":"205.938027ms","start":"2026-02-05T05:24:52.178452Z","end":"2026-02-05T05:24:52.384391Z","steps":["trace[1865149705] 'agreement among raft nodes before linearized reading' (duration: 205.915048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:52.38448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.976016ms","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-02-05T05:24:52.384493Z","caller":"traceutil/trace.go:171","msg":"trace[1041932828] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:794; }","duration":"205.990375ms","start":"2026-02-05T05:24:52.178499Z","end":"2026-02-05T05:24:52.384489Z","steps":["trace[1041932828] 'agreement among raft nodes before linearized reading' (duration: 205.969246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:52.992305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.266039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:24:52.992504Z","caller":"traceutil/trace.go:171","msg":"trace[1759029526] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"219.660711ms","start":"2026-02-05T05:24:52.772824Z","end":"2026-02-05T05:24:52.992484Z","steps":["trace[1759029526] 'process raft request' (duration: 103.155005ms)","trace[1759029526] 'compare' (duration: 116.177313ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:54.884955Z","caller":"traceutil/trace.go:171","msg":"trace[1772516210] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"111.389716ms","start":"2026-02-05T05:24:54.773546Z","end":"2026-02-05T05:24:54.884936Z","steps":["trace[1772516210] 'read index received' (duration: 111.277052ms)","trace[1772516210] 'applied index is now lower than readState.Index' (duration: 111.934µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:54.885038Z","caller":"traceutil/trace.go:171","msg":"trace[1916615172] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"297.226145ms","start":"2026-02-05T05:24:54.587806Z","end":"2026-02-05T05:24:54.885032Z","steps":["trace[1916615172] 'process raft request' (duration: 297.029294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:54.885182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.635645ms","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-02-05T05:24:54.885198Z","caller":"traceutil/trace.go:171","msg":"trace[114667938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"111.702611ms","start":"2026-02-05T05:24:54.77349Z","end":"2026-02-05T05:24:54.885193Z","steps":["trace[114667938] 'agreement among raft nodes before linearized reading' (duration: 111.627415ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:24:55.15241Z","caller":"traceutil/trace.go:171","msg":"trace[2121398034] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"263.376026ms","start":"2026-02-05T05:24:54.888985Z","end":"2026-02-05T05:24:55.152361Z","steps":["trace[2121398034] 'process raft request' (duration: 206.7832ms)","trace[2121398034] 'compare' (duration: 56.168855ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:55.16532Z","caller":"traceutil/trace.go:171","msg":"trace[2069120319] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"168.573013ms","start":"2026-02-05T05:24:54.996724Z","end":"2026-02-05T05:24:55.165297Z","steps":["trace[2069120319] 'read index received' (duration: 99.053767ms)","trace[2069120319] 'applied index is now lower than readState.Index' (duration: 69.517966ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:24:55.165431Z","caller":"traceutil/trace.go:171","msg":"trace[564886977] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"276.015039ms","start":"2026-02-05T05:24:54.889405Z","end":"2026-02-05T05:24:55.16542Z","steps":["trace[564886977] 'process raft request' (duration: 275.77496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:55.165628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.925666ms","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-02-05T05:24:55.165674Z","caller":"traceutil/trace.go:171","msg":"trace[2023340626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"169.013582ms","start":"2026-02-05T05:24:54.996648Z","end":"2026-02-05T05:24:55.165662Z","steps":["trace[2023340626] 'agreement among raft nodes before linearized reading' (duration: 168.904307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:24:55.454916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.480728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:24:55.455663Z","caller":"traceutil/trace.go:171","msg":"trace[813438689] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"283.528506ms","start":"2026-02-05T05:24:55.172111Z","end":"2026-02-05T05:24:55.45564Z","steps":["trace[813438689] 'process raft request' (duration: 149.241663ms)","trace[813438689] 'compare' (duration: 133.361553ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:25:53.031322Z","caller":"traceutil/trace.go:171","msg":"trace[889730069] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"275.163559ms","start":"2026-02-05T05:25:52.756141Z","end":"2026-02-05T05:25:53.031304Z","steps":["trace[889730069] 'process raft request' (duration: 274.735137ms)"],"step_count":1}