{"level":"warn","ts":"2026-02-06T00:40:23.488992Z","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-06T00:40:23.489254Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-06T00:40:23.489397Z","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-06T00:40:23.48941Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-06T00:40:23.489427Z","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-06T00:40:23.490751Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-02-06T00:40:23.49111Z","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.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-06T00:40:23.497227Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.893646ms"} {"level":"info","ts":"2026-02-06T00:40:23.50418Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-02-06T00:40:23.504272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T00:40:23.504319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-02-06T00:40:23.504348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T00:40:23.504364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-02-06T00:40:23.504424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-02-06T00:40:23.511513Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T00:40:23.51609Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T00:40:23.521244Z","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-06T00:40:23.52632Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T00:40:23.527272Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T00:40:23.527383Z","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-06T00:40:23.528499Z","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-06T00:40:23.52852Z","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-06T00:40:23.534257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-02-06T00:40:23.53622Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-06T00:40:23.535297Z","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-06T00:40:23.536439Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T00:40:23.536486Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T00:40:23.535314Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-06T00:40:23.537227Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-06T00:40:23.606104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T00:40:23.606152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T00:40:23.606178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-02-06T00:40:23.6062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-02-06T00:40:23.606229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-06T00:40:23.606255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-02-06T00:40:23.606275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-06T00:40:23.608273Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:40:23.608431Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T00:40:23.608579Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:40:23.609006Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:40:23.609367Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T00:40:23.609383Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T00:40:23.610395Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:40:23.610459Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:40:23.610485Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:40:23.612622Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-02-06T00:40:23.622428Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T00:40:55.296443Z","caller":"traceutil/trace.go:171","msg":"trace[1539620581] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"101.570642ms","start":"2026-02-06T00:40:55.194845Z","end":"2026-02-06T00:40:55.296415Z","steps":["trace[1539620581] 'process raft request' (duration: 98.927481ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:40:56.273023Z","caller":"traceutil/trace.go:171","msg":"trace[501363282] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"557.403396ms","start":"2026-02-06T00:40:55.715602Z","end":"2026-02-06T00:40:56.273006Z","steps":["trace[501363282] 'process raft request' (duration: 557.304133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:40:56.273435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:40:55.715575Z","time spent":"557.560861ms","remote":"127.0.0.1:46656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:40:56.535721Z","caller":"traceutil/trace.go:171","msg":"trace[1721448871] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"444.761066ms","start":"2026-02-06T00:40:56.090945Z","end":"2026-02-06T00:40:56.535706Z","steps":["trace[1721448871] 'read index received' (duration: 182.759337ms)","trace[1721448871] 'applied index is now lower than readState.Index' (duration: 262.001149ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:40:56.535882Z","caller":"traceutil/trace.go:171","msg":"trace[1224421614] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"419.157977ms","start":"2026-02-06T00:40:56.116704Z","end":"2026-02-06T00:40:56.535862Z","steps":["trace[1224421614] 'process raft request' (duration: 418.981902ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:40:56.536028Z","caller":"traceutil/trace.go:171","msg":"trace[828645138] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"615.048877ms","start":"2026-02-06T00:40:55.920972Z","end":"2026-02-06T00:40:56.536021Z","steps":["trace[828645138] 'process raft request' (duration: 614.622776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:40:56.536075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:40:55.920947Z","time spent":"615.101069ms","remote":"127.0.0.1:46656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:40:56.536139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:40:56.116683Z","time spent":"419.304171ms","remote":"127.0.0.1:46656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:40:56.535946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.985171ms","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-06T00:40:56.536279Z","caller":"traceutil/trace.go:171","msg":"trace[998702258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"445.374662ms","start":"2026-02-06T00:40:56.090899Z","end":"2026-02-06T00:40:56.536273Z","steps":["trace[998702258] 'agreement among raft nodes before linearized reading' (duration: 444.982801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:40:56.536303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:40:56.090884Z","time spent":"445.413533ms","remote":"127.0.0.1:46848","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":"warn","ts":"2026-02-06T00:40:56.540238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.403658ms","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-06T00:40:56.540327Z","caller":"traceutil/trace.go:171","msg":"trace[371195890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"440.551813ms","start":"2026-02-06T00:40:56.099765Z","end":"2026-02-06T00:40:56.540317Z","steps":["trace[371195890] 'agreement among raft nodes before linearized reading' (duration: 440.405869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:40:56.540375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:40:56.099745Z","time spent":"440.624324ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-06T00:40:56.54062Z","caller":"traceutil/trace.go:171","msg":"trace[499567922] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"263.321414ms","start":"2026-02-06T00:40:56.277293Z","end":"2026-02-06T00:40:56.540614Z","steps":["trace[499567922] 'process raft request' (duration: 262.749628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:40:56.540916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.158824ms","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-06T00:40:56.540991Z","caller":"traceutil/trace.go:171","msg":"trace[787493544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:546; }","duration":"129.274088ms","start":"2026-02-06T00:40:56.411711Z","end":"2026-02-06T00:40:56.540985Z","steps":["trace[787493544] 'agreement among raft nodes before linearized reading' (duration: 129.132544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:40:56.541138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.115727ms","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-06T00:40:56.541205Z","caller":"traceutil/trace.go:171","msg":"trace[1665255494] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:546; }","duration":"225.219269ms","start":"2026-02-06T00:40:56.31598Z","end":"2026-02-06T00:40:56.541199Z","steps":["trace[1665255494] 'agreement among raft nodes before linearized reading' (duration: 225.142477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:40:56.541326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.153393ms","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-06T00:40:56.541391Z","caller":"traceutil/trace.go:171","msg":"trace[862016281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"278.245395ms","start":"2026-02-06T00:40:56.26314Z","end":"2026-02-06T00:40:56.541386Z","steps":["trace[862016281] 'agreement among raft nodes before linearized reading' (duration: 278.166543ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:40:56.863552Z","caller":"traceutil/trace.go:171","msg":"trace[440587554] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"292.990992ms","start":"2026-02-06T00:40:56.570542Z","end":"2026-02-06T00:40:56.863533Z","steps":["trace[440587554] 'process raft request' (duration: 261.639439ms)","trace[440587554] 'compare' (duration: 31.285222ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:40:56.869408Z","caller":"traceutil/trace.go:171","msg":"trace[408456090] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:562; }","duration":"152.7574ms","start":"2026-02-06T00:40:56.716638Z","end":"2026-02-06T00:40:56.869396Z","steps":["trace[408456090] 'read index received' (duration: 115.551159ms)","trace[408456090] 'applied index is now lower than readState.Index' (duration: 37.205671ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:40:56.869536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.880393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-06T00:40:56.869556Z","caller":"traceutil/trace.go:171","msg":"trace[690739305] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:554; }","duration":"152.940125ms","start":"2026-02-06T00:40:56.71661Z","end":"2026-02-06T00:40:56.86955Z","steps":["trace[690739305] 'agreement among raft nodes before linearized reading' (duration: 152.833772ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:40:56.870276Z","caller":"traceutil/trace.go:171","msg":"trace[324474948] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"299.453816ms","start":"2026-02-06T00:40:56.570813Z","end":"2026-02-06T00:40:56.870267Z","steps":["trace[324474948] 'process raft request' (duration: 298.330916ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:40:56.87037Z","caller":"traceutil/trace.go:171","msg":"trace[1302588654] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"299.514198ms","start":"2026-02-06T00:40:56.570851Z","end":"2026-02-06T00:40:56.870365Z","steps":["trace[1302588654] 'process raft request' (duration: 298.343596ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:40:56.870512Z","caller":"traceutil/trace.go:171","msg":"trace[1348300729] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"285.198653ms","start":"2026-02-06T00:40:56.585307Z","end":"2026-02-06T00:40:56.870506Z","steps":["trace[1348300729] 'process raft request' (duration: 283.912598ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:40:56.870582Z","caller":"traceutil/trace.go:171","msg":"trace[92201372] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"285.214983ms","start":"2026-02-06T00:40:56.585362Z","end":"2026-02-06T00:40:56.870577Z","steps":["trace[92201372] 'process raft request' (duration: 283.891807ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:40:56.870642Z","caller":"traceutil/trace.go:171","msg":"trace[1238360127] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"277.161066ms","start":"2026-02-06T00:40:56.593476Z","end":"2026-02-06T00:40:56.870637Z","steps":["trace[1238360127] 'process raft request' (duration: 275.8007ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:40:56.870701Z","caller":"traceutil/trace.go:171","msg":"trace[563263787] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"276.870099ms","start":"2026-02-06T00:40:56.593825Z","end":"2026-02-06T00:40:56.870695Z","steps":["trace[563263787] 'process raft request' (duration: 275.495632ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:40:56.870759Z","caller":"traceutil/trace.go:171","msg":"trace[2109526121] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"271.227546ms","start":"2026-02-06T00:40:56.599527Z","end":"2026-02-06T00:40:56.870755Z","steps":["trace[2109526121] 'process raft request' (duration: 269.840269ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:40:57.466389Z","caller":"traceutil/trace.go:171","msg":"trace[781163482] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"128.882128ms","start":"2026-02-06T00:40:57.337492Z","end":"2026-02-06T00:40:57.466374Z","steps":["trace[781163482] 'process raft request' (duration: 128.846577ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:40:57.466572Z","caller":"traceutil/trace.go:171","msg":"trace[1929005489] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:575; }","duration":"150.832538ms","start":"2026-02-06T00:40:57.315733Z","end":"2026-02-06T00:40:57.466566Z","steps":["trace[1929005489] 'read index received' (duration: 21.013125ms)","trace[1929005489] 'applied index is now lower than readState.Index' (duration: 129.818763ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:40:57.466569Z","caller":"traceutil/trace.go:171","msg":"trace[627815793] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"165.05815ms","start":"2026-02-06T00:40:57.301488Z","end":"2026-02-06T00:40:57.466546Z","steps":["trace[627815793] 'process raft request' (duration: 164.68237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:40:57.466709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.961051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-06T00:40:57.46673Z","caller":"traceutil/trace.go:171","msg":"trace[932278960] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:560; }","duration":"151.015703ms","start":"2026-02-06T00:40:57.315707Z","end":"2026-02-06T00:40:57.466722Z","steps":["trace[932278960] 'agreement among raft nodes before linearized reading' (duration: 150.920861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:40:57.466827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.886442ms","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-06T00:40:57.466839Z","caller":"traceutil/trace.go:171","msg":"trace[1742087180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"146.912862ms","start":"2026-02-06T00:40:57.319922Z","end":"2026-02-06T00:40:57.466835Z","steps":["trace[1742087180] 'agreement among raft nodes before linearized reading' (duration: 146.874001ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:03.364888Z","caller":"traceutil/trace.go:171","msg":"trace[255138955] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"173.279852ms","start":"2026-02-06T00:41:03.19159Z","end":"2026-02-06T00:41:03.36487Z","steps":["trace[255138955] 'process raft request' (duration: 173.177629ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:03.624461Z","caller":"traceutil/trace.go:171","msg":"trace[1581164878] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"107.342378ms","start":"2026-02-06T00:41:03.517095Z","end":"2026-02-06T00:41:03.624438Z","steps":["trace[1581164878] 'process raft request' (duration: 107.194334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.535621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.17868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:04.535734Z","caller":"traceutil/trace.go:171","msg":"trace[2033066881] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:586; }","duration":"486.331814ms","start":"2026-02-06T00:41:04.049379Z","end":"2026-02-06T00:41:04.535711Z","steps":["trace[2033066881] 'range keys from in-memory index tree' (duration: 486.136988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.535793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:04.049374Z","time spent":"486.405106ms","remote":"127.0.0.1:35908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-02-06T00:41:04.535846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.398906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:04.53595Z","caller":"traceutil/trace.go:171","msg":"trace[516673618] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:586; }","duration":"486.54568ms","start":"2026-02-06T00:41:04.049381Z","end":"2026-02-06T00:41:04.535926Z","steps":["trace[516673618] 'count revisions from in-memory index tree' (duration: 486.322584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.535996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:04.049364Z","time spent":"486.618652ms","remote":"127.0.0.1:35908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:41:04.536092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.487346ms","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-06T00:41:04.536121Z","caller":"traceutil/trace.go:171","msg":"trace[330791356] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:586; }","duration":"474.533127ms","start":"2026-02-06T00:41:04.061577Z","end":"2026-02-06T00:41:04.53611Z","steps":["trace[330791356] 'range keys from in-memory index tree' (duration: 474.398083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.536151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:04.061568Z","time spent":"474.573558ms","remote":"127.0.0.1:35912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-06T00:41:04.536168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.388701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:04.536211Z","caller":"traceutil/trace.go:171","msg":"trace[1131412849] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:586; }","duration":"510.432222ms","start":"2026-02-06T00:41:04.025767Z","end":"2026-02-06T00:41:04.536199Z","steps":["trace[1131412849] 'count revisions from in-memory index tree' (duration: 510.360311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.536244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:04.025749Z","time spent":"510.484064ms","remote":"127.0.0.1:35884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:41:04.53633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.589158ms","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-06T00:41:04.536365Z","caller":"traceutil/trace.go:171","msg":"trace[1403002100] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:586; }","duration":"474.625479ms","start":"2026-02-06T00:41:04.06173Z","end":"2026-02-06T00:41:04.536355Z","steps":["trace[1403002100] 'range keys from in-memory index tree' (duration: 474.549957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.536381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.662289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-06T00:41:04.536401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:04.061703Z","time spent":"474.686311ms","remote":"127.0.0.1:35912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"info","ts":"2026-02-06T00:41:04.536411Z","caller":"traceutil/trace.go:171","msg":"trace[361427580] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:586; }","duration":"510.71191ms","start":"2026-02-06T00:41:04.025691Z","end":"2026-02-06T00:41:04.536403Z","steps":["trace[361427580] 'range keys from in-memory index tree' (duration: 510.538476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.536442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:04.025675Z","time spent":"510.759211ms","remote":"127.0.0.1:35884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-06T00:41:04.536534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.734292ms","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-06T00:41:04.536571Z","caller":"traceutil/trace.go:171","msg":"trace[120234547] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:586; }","duration":"474.771913ms","start":"2026-02-06T00:41:04.061789Z","end":"2026-02-06T00:41:04.53656Z","steps":["trace[120234547] 'count revisions from in-memory index tree' (duration: 474.679211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.536579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.752721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-06T00:41:04.536603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:04.061781Z","time spent":"474.812335ms","remote":"127.0.0.1:35912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-06T00:41:04.536607Z","caller":"traceutil/trace.go:171","msg":"trace[1103300949] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:586; }","duration":"510.789522ms","start":"2026-02-06T00:41:04.025809Z","end":"2026-02-06T00:41:04.536598Z","steps":["trace[1103300949] 'range keys from in-memory index tree' (duration: 510.72154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.536638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:04.025805Z","time spent":"510.825943ms","remote":"127.0.0.1:35884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-06T00:41:04.536745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.319064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:04.536771Z","caller":"traceutil/trace.go:171","msg":"trace[630472962] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:586; }","duration":"502.360375ms","start":"2026-02-06T00:41:04.034402Z","end":"2026-02-06T00:41:04.536763Z","steps":["trace[630472962] 'range keys from in-memory index tree' (duration: 502.286263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.536797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:04.034396Z","time spent":"502.393566ms","remote":"127.0.0.1:35892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-06T00:41:04.536918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.302804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:04.536947Z","caller":"traceutil/trace.go:171","msg":"trace[1104933912] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:586; }","duration":"502.342175ms","start":"2026-02-06T00:41:04.034597Z","end":"2026-02-06T00:41:04.536939Z","steps":["trace[1104933912] 'range keys from in-memory index tree' (duration: 502.278803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.536973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:04.034592Z","time spent":"502.373346ms","remote":"127.0.0.1:35892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-06T00:41:04.537726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.562467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:04.537801Z","caller":"traceutil/trace.go:171","msg":"trace[92782778] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:586; }","duration":"500.65292ms","start":"2026-02-06T00:41:04.037135Z","end":"2026-02-06T00:41:04.537788Z","steps":["trace[92782778] 'count revisions from in-memory index tree' (duration: 500.531486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.537869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:04.037129Z","time spent":"500.699471ms","remote":"127.0.0.1:35892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:41:04.537986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.662697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:04.538039Z","caller":"traceutil/trace.go:171","msg":"trace[1256055882] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:586; }","duration":"488.704618ms","start":"2026-02-06T00:41:04.049301Z","end":"2026-02-06T00:41:04.538005Z","steps":["trace[1256055882] 'range keys from in-memory index tree' (duration: 488.625126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:04.538071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:04.049294Z","time spent":"488.76806ms","remote":"127.0.0.1:35908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"info","ts":"2026-02-06T00:41:14.729108Z","caller":"traceutil/trace.go:171","msg":"trace[326781912] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"320.102312ms","start":"2026-02-06T00:41:14.40899Z","end":"2026-02-06T00:41:14.729093Z","steps":["trace[326781912] 'process raft request' (duration: 319.969928ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:14.729281Z","caller":"traceutil/trace.go:171","msg":"trace[1402530633] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:670; }","duration":"277.864246ms","start":"2026-02-06T00:41:14.451369Z","end":"2026-02-06T00:41:14.729233Z","steps":["trace[1402530633] 'read index received' (duration: 277.856716ms)","trace[1402530633] 'applied index is now lower than readState.Index' (duration: 5.64µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:14.729328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:14.408978Z","time spent":"320.176714ms","remote":"127.0.0.1:46784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:41:14.729451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.04438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:14.729479Z","caller":"traceutil/trace.go:171","msg":"trace[337223718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"278.135213ms","start":"2026-02-06T00:41:14.451335Z","end":"2026-02-06T00:41:14.72947Z","steps":["trace[337223718] 'agreement among raft nodes before linearized reading' (duration: 277.994779ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:15.594698Z","caller":"traceutil/trace.go:171","msg":"trace[356719289] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"191.741038ms","start":"2026-02-06T00:41:15.40295Z","end":"2026-02-06T00:41:15.594691Z","steps":["trace[356719289] 'process raft request' (duration: 191.43241ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:15.594521Z","caller":"traceutil/trace.go:171","msg":"trace[1172856497] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"136.507772ms","start":"2026-02-06T00:41:15.457997Z","end":"2026-02-06T00:41:15.594505Z","steps":["trace[1172856497] 'read index received' (duration: 136.488752ms)","trace[1172856497] 'applied index is now lower than readState.Index' (duration: 18.58µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:15.595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.991245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:15.595018Z","caller":"traceutil/trace.go:171","msg":"trace[2075560157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"137.019206ms","start":"2026-02-06T00:41:15.457993Z","end":"2026-02-06T00:41:15.595012Z","steps":["trace[2075560157] 'agreement among raft nodes before linearized reading' (duration: 136.920093ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:15.635166Z","caller":"traceutil/trace.go:171","msg":"trace[567878421] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"164.968518ms","start":"2026-02-06T00:41:15.470181Z","end":"2026-02-06T00:41:15.635149Z","steps":["trace[567878421] 'process raft request' (duration: 164.892836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:26.627929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.119798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:26.627996Z","caller":"traceutil/trace.go:171","msg":"trace[1349098414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"176.219201ms","start":"2026-02-06T00:41:26.451761Z","end":"2026-02-06T00:41:26.62798Z","steps":["trace[1349098414] 'range keys from in-memory index tree' (duration: 176.073587ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:27.443132Z","caller":"traceutil/trace.go:171","msg":"trace[926231781] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"383.114797ms","start":"2026-02-06T00:41:27.060002Z","end":"2026-02-06T00:41:27.443116Z","steps":["trace[926231781] 'process raft request' (duration: 382.985003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:27.443276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:27.059991Z","time spent":"383.192348ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:41:27.443405Z","caller":"traceutil/trace.go:171","msg":"trace[1455215660] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:767; }","duration":"166.391346ms","start":"2026-02-06T00:41:27.276994Z","end":"2026-02-06T00:41:27.443385Z","steps":["trace[1455215660] 'read index received' (duration: 165.990645ms)","trace[1455215660] 'applied index is now lower than readState.Index' (duration: 399.981µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:27.443547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.544701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T00:41:27.44357Z","caller":"traceutil/trace.go:171","msg":"trace[137900322] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:744; }","duration":"166.614082ms","start":"2026-02-06T00:41:27.276947Z","end":"2026-02-06T00:41:27.443562Z","steps":["trace[137900322] 'agreement among raft nodes before linearized reading' (duration: 166.50464ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:27.443615Z","caller":"traceutil/trace.go:171","msg":"trace[1753345050] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"383.377364ms","start":"2026-02-06T00:41:27.060211Z","end":"2026-02-06T00:41:27.443588Z","steps":["trace[1753345050] 'process raft request' (duration: 382.882251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:27.443703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:27.060201Z","time spent":"383.435885ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:41:27.641124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.80738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:41:27.641249Z","caller":"traceutil/trace.go:171","msg":"trace[512937866] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"133.000718ms","start":"2026-02-06T00:41:27.508223Z","end":"2026-02-06T00:41:27.641224Z","steps":["trace[512937866] 'process raft request' (duration: 27.88077ms)","trace[512937866] 'compare' (duration: 104.689437ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:41:41.289371Z","caller":"traceutil/trace.go:171","msg":"trace[1121944538] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"184.363575ms","start":"2026-02-06T00:41:41.104993Z","end":"2026-02-06T00:41:41.289356Z","steps":["trace[1121944538] 'process raft request' (duration: 184.210571ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:41.289557Z","caller":"traceutil/trace.go:171","msg":"trace[527108348] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:816; }","duration":"130.389903ms","start":"2026-02-06T00:41:41.15916Z","end":"2026-02-06T00:41:41.28955Z","steps":["trace[527108348] 'read index received' (duration: 130.387183ms)","trace[527108348] 'applied index is now lower than readState.Index' (duration: 2.24µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:41.289672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.496025ms","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-06T00:41:41.289691Z","caller":"traceutil/trace.go:171","msg":"trace[1110069368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"130.541666ms","start":"2026-02-06T00:41:41.159144Z","end":"2026-02-06T00:41:41.289686Z","steps":["trace[1110069368] 'agreement among raft nodes before linearized reading' (duration: 130.436503ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:43.45247Z","caller":"traceutil/trace.go:171","msg":"trace[387463198] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"100.540579ms","start":"2026-02-06T00:41:43.351914Z","end":"2026-02-06T00:41:43.452455Z","steps":["trace[387463198] 'process raft request' (duration: 90.548374ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:43.663345Z","caller":"traceutil/trace.go:171","msg":"trace[2090262521] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"141.181398ms","start":"2026-02-06T00:41:43.522142Z","end":"2026-02-06T00:41:43.663323Z","steps":["trace[2090262521] 'process raft request' (duration: 141.100135ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:43.663442Z","caller":"traceutil/trace.go:171","msg":"trace[1849355218] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"141.357503ms","start":"2026-02-06T00:41:43.52201Z","end":"2026-02-06T00:41:43.663367Z","steps":["trace[1849355218] 'process raft request' (duration: 112.292041ms)","trace[1849355218] 'compare' (duration: 28.775544ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:44.244807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.545069ms","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-06T00:41:44.244884Z","caller":"traceutil/trace.go:171","msg":"trace[1848545257] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"370.670563ms","start":"2026-02-06T00:41:43.874196Z","end":"2026-02-06T00:41:44.244866Z","steps":["trace[1848545257] 'range keys from in-memory index tree' (duration: 370.370555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:44.244947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:43.874176Z","time spent":"370.759224ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:41:44.633394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.304951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:44.63358Z","caller":"traceutil/trace.go:171","msg":"trace[908936769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"179.532537ms","start":"2026-02-06T00:41:44.45403Z","end":"2026-02-06T00:41:44.633562Z","steps":["trace[908936769] 'range keys from in-memory index tree' (duration: 179.231579ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:57.670195Z","caller":"traceutil/trace.go:171","msg":"trace[513882841] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"205.057315ms","start":"2026-02-06T00:41:57.465121Z","end":"2026-02-06T00:41:57.670179Z","steps":["trace[513882841] 'process raft request' (duration: 204.951172ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:57.670559Z","caller":"traceutil/trace.go:171","msg":"trace[1437027546] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:917; }","duration":"139.534005ms","start":"2026-02-06T00:41:57.531017Z","end":"2026-02-06T00:41:57.670551Z","steps":["trace[1437027546] 'read index received' (duration: 139.525344ms)","trace[1437027546] 'applied index is now lower than readState.Index' (duration: 8.04µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:57.670636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.609047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:41:57.670653Z","caller":"traceutil/trace.go:171","msg":"trace[479657910] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:883; }","duration":"139.667278ms","start":"2026-02-06T00:41:57.530981Z","end":"2026-02-06T00:41:57.670648Z","steps":["trace[479657910] 'agreement among raft nodes before linearized reading' (duration: 139.609627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:57.670798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.558284ms","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-06T00:41:57.670811Z","caller":"traceutil/trace.go:171","msg":"trace[2020677875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"125.615415ms","start":"2026-02-06T00:41:57.545192Z","end":"2026-02-06T00:41:57.670807Z","steps":["trace[2020677875] 'agreement among raft nodes before linearized reading' (duration: 125.532213ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:58.082563Z","caller":"traceutil/trace.go:171","msg":"trace[1401691529] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"341.132668ms","start":"2026-02-06T00:41:57.741414Z","end":"2026-02-06T00:41:58.082547Z","steps":["trace[1401691529] 'process raft request' (duration: 341.018865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:58.082671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:57.741396Z","time spent":"341.218041ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:41:58.823197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.502915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:41:58.823266Z","caller":"traceutil/trace.go:171","msg":"trace[2039644778] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"915.870348ms","start":"2026-02-06T00:41:57.907386Z","end":"2026-02-06T00:41:58.823257Z","steps":["trace[2039644778] 'read index received' (duration: 175.635984ms)","trace[2039644778] 'applied index is now lower than readState.Index' (duration: 740.233714ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:41:58.823363Z","caller":"traceutil/trace.go:171","msg":"trace[633018276] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"1.081541487s","start":"2026-02-06T00:41:57.741816Z","end":"2026-02-06T00:41:58.823357Z","steps":["trace[633018276] 'process raft request' (duration: 454.822216ms)","trace[633018276] 'compare' (duration: 626.29995ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:58.823393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:57.741804Z","time spent":"1.081574108s","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:41:58.823493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.104964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:58.823508Z","caller":"traceutil/trace.go:171","msg":"trace[465573007] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:886; }","duration":"916.144656ms","start":"2026-02-06T00:41:57.907358Z","end":"2026-02-06T00:41:58.823502Z","steps":["trace[465573007] 'agreement among raft nodes before linearized reading' (duration: 916.116455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:58.823522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:57.907345Z","time spent":"916.172726ms","remote":"127.0.0.1:46890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:41:58.825843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.887211ms","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-06T00:41:58.825864Z","caller":"traceutil/trace.go:171","msg":"trace[168909658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:887; }","duration":"433.929432ms","start":"2026-02-06T00:41:58.391929Z","end":"2026-02-06T00:41:58.825858Z","steps":["trace[168909658] 'agreement among raft nodes before linearized reading' (duration: 433.85102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:58.825878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:58.391914Z","time spent":"433.960783ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-06T00:41:58.825948Z","caller":"traceutil/trace.go:171","msg":"trace[1488955016] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"331.03547ms","start":"2026-02-06T00:41:58.494908Z","end":"2026-02-06T00:41:58.825943Z","steps":["trace[1488955016] 'process raft request' (duration: 330.818394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:58.825975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:58.494888Z","time spent":"331.071161ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:42:00.934922Z","caller":"traceutil/trace.go:171","msg":"trace[880136993] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"107.614897ms","start":"2026-02-06T00:42:00.827292Z","end":"2026-02-06T00:42:00.934907Z","steps":["trace[880136993] 'read index received' (duration: 107.482744ms)","trace[880136993] 'applied index is now lower than readState.Index' (duration: 131.623µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:00.935018Z","caller":"traceutil/trace.go:171","msg":"trace[106238813] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"167.744344ms","start":"2026-02-06T00:42:00.767268Z","end":"2026-02-06T00:42:00.935013Z","steps":["trace[106238813] 'process raft request' (duration: 167.548449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:00.935172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.872034ms","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-06T00:42:00.935189Z","caller":"traceutil/trace.go:171","msg":"trace[1032102311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:892; }","duration":"107.900045ms","start":"2026-02-06T00:42:00.827284Z","end":"2026-02-06T00:42:00.935184Z","steps":["trace[1032102311] 'agreement among raft nodes before linearized reading' (duration: 107.814992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:01.11063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.155257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:42:01.110692Z","caller":"traceutil/trace.go:171","msg":"trace[540684883] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"172.341147ms","start":"2026-02-06T00:42:00.93834Z","end":"2026-02-06T00:42:01.110681Z","steps":["trace[540684883] 'process raft request' (duration: 19.084777ms)","trace[540684883] 'compare' (duration: 153.094165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:42:01.492759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.175255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:42:01.494249Z","caller":"traceutil/trace.go:171","msg":"trace[1715123971] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"554.514504ms","start":"2026-02-06T00:42:00.939709Z","end":"2026-02-06T00:42:01.494224Z","steps":["trace[1715123971] 'process raft request' (duration: 285.796669ms)","trace[1715123971] 'compare' (duration: 266.928617ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:42:01.494606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:00.939692Z","time spent":"554.643828ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:42:03.553968Z","caller":"traceutil/trace.go:171","msg":"trace[980404900] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"285.755066ms","start":"2026-02-06T00:42:03.268199Z","end":"2026-02-06T00:42:03.553954Z","steps":["trace[980404900] 'read index received' (duration: 285.592032ms)","trace[980404900] 'applied index is now lower than readState.Index' (duration: 162.514µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:03.55406Z","caller":"traceutil/trace.go:171","msg":"trace[1058645345] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"436.612953ms","start":"2026-02-06T00:42:03.11744Z","end":"2026-02-06T00:42:03.554053Z","steps":["trace[1058645345] 'process raft request' (duration: 436.361376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:03.554156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:03.117431Z","time spent":"436.657844ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:42:03.554206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.757875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:42:03.55425Z","caller":"traceutil/trace.go:171","msg":"trace[363033783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"103.828347ms","start":"2026-02-06T00:42:03.450411Z","end":"2026-02-06T00:42:03.554239Z","steps":["trace[363033783] 'agreement among raft nodes before linearized reading' (duration: 103.760865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:03.554376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.174517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:42:03.55439Z","caller":"traceutil/trace.go:171","msg":"trace[1524049706] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:898; }","duration":"286.213019ms","start":"2026-02-06T00:42:03.268173Z","end":"2026-02-06T00:42:03.554386Z","steps":["trace[1524049706] 'agreement among raft nodes before linearized reading' (duration: 286.184668ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:07.897797Z","caller":"traceutil/trace.go:171","msg":"trace[743385651] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"163.966524ms","start":"2026-02-06T00:42:07.733806Z","end":"2026-02-06T00:42:07.897773Z","steps":["trace[743385651] 'process raft request' (duration: 154.822151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:14.123969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.989771ms","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-06T00:42:14.124048Z","caller":"traceutil/trace.go:171","msg":"trace[1154286730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"403.071593ms","start":"2026-02-06T00:42:13.720939Z","end":"2026-02-06T00:42:14.124011Z","steps":["trace[1154286730] 'range keys from in-memory index tree' (duration: 402.864628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:14.124075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:13.72093Z","time spent":"403.139854ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T00:42:14.124212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.211923ms","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-06T00:42:14.124287Z","caller":"traceutil/trace.go:171","msg":"trace[693099068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:926; }","duration":"201.361076ms","start":"2026-02-06T00:42:13.922908Z","end":"2026-02-06T00:42:14.124269Z","steps":["trace[693099068] 'range keys from in-memory index tree' (duration: 201.069559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:14.124467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.902321ms","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-06T00:42:14.124491Z","caller":"traceutil/trace.go:171","msg":"trace[1367116987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"384.939001ms","start":"2026-02-06T00:42:13.739545Z","end":"2026-02-06T00:42:14.124484Z","steps":["trace[1367116987] 'range keys from in-memory index tree' (duration: 384.828168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:14.12452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:13.739537Z","time spent":"384.977962ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:42:14.124644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.511143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:42:14.124674Z","caller":"traceutil/trace.go:171","msg":"trace[1847618691] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:926; }","duration":"270.565924ms","start":"2026-02-06T00:42:13.8541Z","end":"2026-02-06T00:42:14.124666Z","steps":["trace[1847618691] 'count revisions from in-memory index tree' (duration: 270.469642ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:14.3445Z","caller":"traceutil/trace.go:171","msg":"trace[395273932] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"204.616631ms","start":"2026-02-06T00:42:14.139861Z","end":"2026-02-06T00:42:14.344478Z","steps":["trace[395273932] 'process raft request' (duration: 204.464917ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:14.49849Z","caller":"traceutil/trace.go:171","msg":"trace[743217248] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"356.378862ms","start":"2026-02-06T00:42:14.142089Z","end":"2026-02-06T00:42:14.498467Z","steps":["trace[743217248] 'process raft request' (duration: 319.499533ms)","trace[743217248] 'compare' (duration: 36.355665ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:42:14.498613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:14.142082Z","time spent":"356.466894ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:42:14.498781Z","caller":"traceutil/trace.go:171","msg":"trace[687273606] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"356.633078ms","start":"2026-02-06T00:42:14.142136Z","end":"2026-02-06T00:42:14.498769Z","steps":["trace[687273606] 'process raft request' (duration: 355.912039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:14.498832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:14.142119Z","time spent":"356.68384ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:42:14.498952Z","caller":"traceutil/trace.go:171","msg":"trace[348225786] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"355.408766ms","start":"2026-02-06T00:42:14.143534Z","end":"2026-02-06T00:42:14.498943Z","steps":["trace[348225786] 'process raft request' (duration: 354.548793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:14.498999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:14.143529Z","time spent":"355.442617ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:42:17.907153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.133591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:42:17.907463Z","caller":"traceutil/trace.go:171","msg":"trace[356715093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"456.47924ms","start":"2026-02-06T00:42:17.450967Z","end":"2026-02-06T00:42:17.907446Z","steps":["trace[356715093] 'range keys from in-memory index tree' (duration: 456.09048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:17.907596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:17.450954Z","time spent":"456.630494ms","remote":"127.0.0.1:46604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-06T00:42:17.993356Z","caller":"traceutil/trace.go:171","msg":"trace[1962654048] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"450.320886ms","start":"2026-02-06T00:42:17.543013Z","end":"2026-02-06T00:42:17.993334Z","steps":["trace[1962654048] 'process raft request' (duration: 450.09705ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:17.993341Z","caller":"traceutil/trace.go:171","msg":"trace[1960543304] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"350.203147ms","start":"2026-02-06T00:42:17.64311Z","end":"2026-02-06T00:42:17.993313Z","steps":["trace[1960543304] 'read index received' (duration: 349.891549ms)","trace[1960543304] 'applied index is now lower than readState.Index' (duration: 310.488µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:42:17.993493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:17.542986Z","time spent":"450.435249ms","remote":"127.0.0.1:46690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:42:17.993621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.496035ms","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-06T00:42:17.993667Z","caller":"traceutil/trace.go:171","msg":"trace[2050844223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:943; }","duration":"350.572787ms","start":"2026-02-06T00:42:17.643081Z","end":"2026-02-06T00:42:17.993654Z","steps":["trace[2050844223] 'agreement among raft nodes before linearized reading' (duration: 350.378842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:17.993708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:17.643068Z","time spent":"350.627058ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:42:17.993983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.510855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:42:17.994029Z","caller":"traceutil/trace.go:171","msg":"trace[1924571108] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:943; }","duration":"191.588277ms","start":"2026-02-06T00:42:17.802428Z","end":"2026-02-06T00:42:17.994016Z","steps":["trace[1924571108] 'agreement among raft nodes before linearized reading' (duration: 191.505415ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:18.145951Z","caller":"traceutil/trace.go:171","msg":"trace[1849086997] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"147.044195ms","start":"2026-02-06T00:42:17.998881Z","end":"2026-02-06T00:42:18.145926Z","steps":["trace[1849086997] 'process raft request' (duration: 146.747677ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:18.14651Z","caller":"traceutil/trace.go:171","msg":"trace[897083640] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"147.590969ms","start":"2026-02-06T00:42:17.998907Z","end":"2026-02-06T00:42:18.146498Z","steps":["trace[897083640] 'process raft request' (duration: 147.537368ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:30.732982Z","caller":"traceutil/trace.go:171","msg":"trace[260149005] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"278.306349ms","start":"2026-02-06T00:42:30.454652Z","end":"2026-02-06T00:42:30.732959Z","steps":["trace[260149005] 'read index received' (duration: 278.098864ms)","trace[260149005] 'applied index is now lower than readState.Index' (duration: 206.625µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:30.733268Z","caller":"traceutil/trace.go:171","msg":"trace[238078644] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"320.367586ms","start":"2026-02-06T00:42:30.412886Z","end":"2026-02-06T00:42:30.733253Z","steps":["trace[238078644] 'process raft request' (duration: 319.923955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:30.733366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:30.41286Z","time spent":"320.439439ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:42:30.733538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.879905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:42:30.733569Z","caller":"traceutil/trace.go:171","msg":"trace[1338087569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"278.929256ms","start":"2026-02-06T00:42:30.45463Z","end":"2026-02-06T00:42:30.733559Z","steps":["trace[1338087569] 'agreement among raft nodes before linearized reading' (duration: 278.880325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:30.734475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.081907ms","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-06T00:42:30.734512Z","caller":"traceutil/trace.go:171","msg":"trace[1716161996] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1036; }","duration":"158.156749ms","start":"2026-02-06T00:42:30.576345Z","end":"2026-02-06T00:42:30.734501Z","steps":["trace[1716161996] 'agreement among raft nodes before linearized reading' (duration: 158.036076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:30.735106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.058717ms","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-06T00:42:30.735143Z","caller":"traceutil/trace.go:171","msg":"trace[487573967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1036; }","duration":"155.112278ms","start":"2026-02-06T00:42:30.58002Z","end":"2026-02-06T00:42:30.735133Z","steps":["trace[487573967] 'agreement among raft nodes before linearized reading' (duration: 154.743318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:30.735274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.754578ms","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-06T00:42:30.735341Z","caller":"traceutil/trace.go:171","msg":"trace[1288792955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1036; }","duration":"157.840001ms","start":"2026-02-06T00:42:30.577486Z","end":"2026-02-06T00:42:30.735326Z","steps":["trace[1288792955] 'agreement among raft nodes before linearized reading' (duration: 157.652426ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:31.078932Z","caller":"traceutil/trace.go:171","msg":"trace[1438444738] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"337.951033ms","start":"2026-02-06T00:42:30.740955Z","end":"2026-02-06T00:42:31.078906Z","steps":["trace[1438444738] 'process raft request' (duration: 293.582825ms)","trace[1438444738] 'compare' (duration: 44.248975ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:42:31.079072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:30.740944Z","time spent":"338.055827ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:42:31.080108Z","caller":"traceutil/trace.go:171","msg":"trace[188848593] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"338.132779ms","start":"2026-02-06T00:42:30.741962Z","end":"2026-02-06T00:42:31.080095Z","steps":["trace[188848593] 'process raft request' (duration: 337.623495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:31.080196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:30.741943Z","time spent":"338.21452ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:42:31.080382Z","caller":"traceutil/trace.go:171","msg":"trace[1723725406] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"230.508831ms","start":"2026-02-06T00:42:30.849855Z","end":"2026-02-06T00:42:31.080364Z","steps":["trace[1723725406] 'process raft request' (duration: 229.793572ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:36.266744Z","caller":"traceutil/trace.go:171","msg":"trace[1953012758] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1901; }","duration":"143.939961ms","start":"2026-02-06T00:45:36.122784Z","end":"2026-02-06T00:45:36.266724Z","steps":["trace[1953012758] 'read index received' (duration: 143.804598ms)","trace[1953012758] 'applied index is now lower than readState.Index' (duration: 134.503µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:45:36.267076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.27323ms","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-06T00:45:36.267109Z","caller":"traceutil/trace.go:171","msg":"trace[1430414319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1824; }","duration":"144.335312ms","start":"2026-02-06T00:45:36.122767Z","end":"2026-02-06T00:45:36.267102Z","steps":["trace[1430414319] 'agreement among raft nodes before linearized reading' (duration: 144.204369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:36.267578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.769676ms","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-06T00:45:36.267822Z","caller":"traceutil/trace.go:171","msg":"trace[354315599] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"146.995712ms","start":"2026-02-06T00:45:36.120816Z","end":"2026-02-06T00:45:36.267811Z","steps":["trace[354315599] 'process raft request' (duration: 145.819871ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:36.268531Z","caller":"traceutil/trace.go:171","msg":"trace[651564315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1824; }","duration":"143.824328ms","start":"2026-02-06T00:45:36.123788Z","end":"2026-02-06T00:45:36.267612Z","steps":["trace[651564315] 'agreement among raft nodes before linearized reading' (duration: 143.743536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:36.719717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.679008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:45:36.719884Z","caller":"traceutil/trace.go:171","msg":"trace[218799131] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1902; }","duration":"280.375256ms","start":"2026-02-06T00:45:36.439492Z","end":"2026-02-06T00:45:36.719867Z","steps":["trace[218799131] 'read index received' (duration: 40.591µs)","trace[218799131] 'applied index is now lower than readState.Index' (duration: 280.333455ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:45:36.720108Z","caller":"traceutil/trace.go:171","msg":"trace[688177196] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"447.434413ms","start":"2026-02-06T00:45:36.27266Z","end":"2026-02-06T00:45:36.720095Z","steps":["trace[688177196] 'process raft request' (duration: 38.282383ms)","trace[688177196] 'compare' (duration: 408.237816ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:45:36.720173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:36.272647Z","time spent":"447.492435ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:45:36.720348Z","caller":"traceutil/trace.go:171","msg":"trace[285385535] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"438.647894ms","start":"2026-02-06T00:45:36.281689Z","end":"2026-02-06T00:45:36.720337Z","steps":["trace[285385535] 'process raft request' (duration: 438.12237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:36.720401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:36.28167Z","time spent":"438.701024ms","remote":"127.0.0.1:46848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:45:36.720608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.111725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-06T00:45:36.72064Z","caller":"traceutil/trace.go:171","msg":"trace[701851479] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1826; }","duration":"281.174727ms","start":"2026-02-06T00:45:36.439453Z","end":"2026-02-06T00:45:36.720628Z","steps":["trace[701851479] 'agreement among raft nodes before linearized reading' (duration: 281.062294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:36.72079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.391392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:36.720853Z","caller":"traceutil/trace.go:171","msg":"trace[2081214315] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1826; }","duration":"110.481205ms","start":"2026-02-06T00:45:36.610362Z","end":"2026-02-06T00:45:36.720844Z","steps":["trace[2081214315] 'agreement among raft nodes before linearized reading' (duration: 110.403883ms)"],"step_count":1}