{"level":"warn","ts":"2026-02-02T16:01:06.367719Z","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-02T16:01:06.367789Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.122: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.122:2380","--initial-cluster=instance=https://199.204.45.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.122: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-02T16:01:06.367847Z","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-02T16:01:06.367855Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-02-02T16:01:06.36787Z","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-02T16:01:06.368458Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"]} {"level":"info","ts":"2026-02-02T16:01:06.36852Z","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.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.122: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-02T16:01:06.373161Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.457113ms"} {"level":"info","ts":"2026-02-02T16:01:06.379308Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d2c3f297b56db0c4","cluster-id":"becafec23d27c587"} {"level":"info","ts":"2026-02-02T16:01:06.379508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T16:01:06.379549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 0"} {"level":"info","ts":"2026-02-02T16:01:06.379572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d2c3f297b56db0c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T16:01:06.379599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 1"} {"level":"info","ts":"2026-02-02T16:01:06.379635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"warn","ts":"2026-02-02T16:01:06.386541Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T16:01:06.387828Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T16:01:06.389388Z","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-02T16:01:06.392423Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d2c3f297b56db0c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T16:01:06.393048Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d2c3f297b56db0c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T16:01:06.393114Z","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-02T16:01:06.39313Z","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-02T16:01:06.393143Z","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-02T16:01:06.397751Z","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-02T16:01:06.397884Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d2c3f297b56db0c4","initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T16:01:06.397903Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T16:01:06.397959Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-02-02T16:01:06.397966Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-02-02T16:01:06.405704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"info","ts":"2026-02-02T16:01:06.405787Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","added-peer-id":"d2c3f297b56db0c4","added-peer-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-02-02T16:01:06.480667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T16:01:06.480701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T16:01:06.480712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgPreVoteResp from d2c3f297b56db0c4 at term 1"} {"level":"info","ts":"2026-02-02T16:01:06.480723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became candidate at term 2"} {"level":"info","ts":"2026-02-02T16:01:06.480732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgVoteResp from d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-02-02T16:01:06.48074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became leader at term 2"} {"level":"info","ts":"2026-02-02T16:01:06.480752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d2c3f297b56db0c4 elected leader d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-02-02T16:01:06.488117Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T16:01:06.490964Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d2c3f297b56db0c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.122:2379]}","request-path":"/0/members/d2c3f297b56db0c4/attributes","cluster-id":"becafec23d27c587","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T16:01:06.491155Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T16:01:06.491352Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T16:01:06.491407Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T16:01:06.491422Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T16:01:06.491434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T16:01:06.492067Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T16:01:06.492161Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T16:01:06.494516Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T16:01:06.496585Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.122:2379"} {"level":"info","ts":"2026-02-02T16:01:22.493415Z","caller":"traceutil/trace.go:171","msg":"trace[1361122831] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"297.070607ms","start":"2026-02-02T16:01:22.196221Z","end":"2026-02-02T16:01:22.493291Z","steps":["trace[1361122831] 'process raft request' (duration: 296.898793ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:23.320173Z","caller":"traceutil/trace.go:171","msg":"trace[1559522112] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"174.555664ms","start":"2026-02-02T16:01:23.145596Z","end":"2026-02-02T16:01:23.320151Z","steps":["trace[1559522112] 'read index received' (duration: 174.321208ms)","trace[1559522112] 'applied index is now lower than readState.Index' (duration: 233.486µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:23.320561Z","caller":"traceutil/trace.go:171","msg":"trace[11183316] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"653.508146ms","start":"2026-02-02T16:01:22.667013Z","end":"2026-02-02T16:01:23.320521Z","steps":["trace[11183316] 'process raft request' (duration: 652.959292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:23.321055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:22.666977Z","time spent":"653.642849ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:01:23.321255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.658761ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:23.321316Z","caller":"traceutil/trace.go:171","msg":"trace[2054493828] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:396; }","duration":"175.719012ms","start":"2026-02-02T16:01:23.145588Z","end":"2026-02-02T16:01:23.321307Z","steps":["trace[2054493828] 'agreement among raft nodes before linearized reading' (duration: 175.64275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:23.321477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.999527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:23.321502Z","caller":"traceutil/trace.go:171","msg":"trace[2107299096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:396; }","duration":"169.061809ms","start":"2026-02-02T16:01:23.152434Z","end":"2026-02-02T16:01:23.321496Z","steps":["trace[2107299096] 'agreement among raft nodes before linearized reading' (duration: 169.019228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:23.321638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.607842ms","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-02T16:01:23.321662Z","caller":"traceutil/trace.go:171","msg":"trace[1628276196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:396; }","duration":"156.650354ms","start":"2026-02-02T16:01:23.165006Z","end":"2026-02-02T16:01:23.321656Z","steps":["trace[1628276196] 'agreement among raft nodes before linearized reading' (duration: 156.602812ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:27.486375Z","caller":"traceutil/trace.go:171","msg":"trace[1014093452] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"119.192822ms","start":"2026-02-02T16:01:27.367164Z","end":"2026-02-02T16:01:27.486357Z","steps":["trace[1014093452] 'process raft request' (duration: 119.073119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:37.754058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.333343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:01:37.75412Z","caller":"traceutil/trace.go:171","msg":"trace[1898871524] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"221.539549ms","start":"2026-02-02T16:01:37.532572Z","end":"2026-02-02T16:01:37.754111Z","steps":["trace[1898871524] 'read index received' (duration: 58.989021ms)","trace[1898871524] 'applied index is now lower than readState.Index' (duration: 162.548998ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:37.75424Z","caller":"traceutil/trace.go:171","msg":"trace[577187043] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"224.466901ms","start":"2026-02-02T16:01:37.529767Z","end":"2026-02-02T16:01:37.754233Z","steps":["trace[577187043] 'process raft request' (duration: 61.79443ms)","trace[577187043] 'compare' (duration: 162.21126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:37.754342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.766975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-02T16:01:37.754356Z","caller":"traceutil/trace.go:171","msg":"trace[453678787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"221.800286ms","start":"2026-02-02T16:01:37.532551Z","end":"2026-02-02T16:01:37.754351Z","steps":["trace[453678787] 'agreement among raft nodes before linearized reading' (duration: 221.771615ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:37.982721Z","caller":"traceutil/trace.go:171","msg":"trace[1660053660] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"337.891351ms","start":"2026-02-02T16:01:37.644816Z","end":"2026-02-02T16:01:37.982707Z","steps":["trace[1660053660] 'process raft request' (duration: 337.785649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:37.98282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:37.644798Z","time spent":"337.981564ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T16:01:37.983089Z","caller":"traceutil/trace.go:171","msg":"trace[1485581816] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:529; }","duration":"228.949841ms","start":"2026-02-02T16:01:37.754133Z","end":"2026-02-02T16:01:37.983083Z","steps":["trace[1485581816] 'read index received' (duration: 228.947151ms)","trace[1485581816] 'applied index is now lower than readState.Index' (duration: 2.2µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:37.983148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.069964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T16:01:37.983161Z","caller":"traceutil/trace.go:171","msg":"trace[1575577338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"431.131845ms","start":"2026-02-02T16:01:37.552026Z","end":"2026-02-02T16:01:37.983158Z","steps":["trace[1575577338] 'agreement among raft nodes before linearized reading' (duration: 431.076824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:37.983174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:37.552012Z","time spent":"431.159496ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T16:01:37.983241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.961409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:37.983253Z","caller":"traceutil/trace.go:171","msg":"trace[1810599828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"398.040061ms","start":"2026-02-02T16:01:37.585209Z","end":"2026-02-02T16:01:37.983249Z","steps":["trace[1810599828] 'agreement among raft nodes before linearized reading' (duration: 398.022781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:37.983265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:37.585196Z","time spent":"398.065312ms","remote":"127.0.0.1:51024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T16:01:37.983331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.308021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T16:01:37.983343Z","caller":"traceutil/trace.go:171","msg":"trace[611737169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"425.345843ms","start":"2026-02-02T16:01:37.557993Z","end":"2026-02-02T16:01:37.983339Z","steps":["trace[611737169] 'agreement among raft nodes before linearized reading' (duration: 425.323262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:37.983356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:37.557988Z","time spent":"425.365793ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-02T16:01:38.023564Z","caller":"traceutil/trace.go:171","msg":"trace[1684835447] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"178.605104ms","start":"2026-02-02T16:01:37.844943Z","end":"2026-02-02T16:01:38.023549Z","steps":["trace[1684835447] 'process raft request' (duration: 178.574503ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:38.023839Z","caller":"traceutil/trace.go:171","msg":"trace[154466593] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"266.881265ms","start":"2026-02-02T16:01:37.756953Z","end":"2026-02-02T16:01:38.023834Z","steps":["trace[154466593] 'process raft request' (duration: 266.378733ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:38.145501Z","caller":"traceutil/trace.go:171","msg":"trace[1843058467] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"100.122972ms","start":"2026-02-02T16:01:38.045364Z","end":"2026-02-02T16:01:38.145487Z","steps":["trace[1843058467] 'process raft request' (duration: 97.118208ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:38.208887Z","caller":"traceutil/trace.go:171","msg":"trace[1526355283] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"125.684391ms","start":"2026-02-02T16:01:38.083187Z","end":"2026-02-02T16:01:38.208871Z","steps":["trace[1526355283] 'process raft request' (duration: 125.585049ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:38.209056Z","caller":"traceutil/trace.go:171","msg":"trace[1885511586] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"124.81785ms","start":"2026-02-02T16:01:38.084233Z","end":"2026-02-02T16:01:38.209051Z","steps":["trace[1885511586] 'process raft request' (duration: 124.613235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:38.658369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.284648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:01:38.659134Z","caller":"traceutil/trace.go:171","msg":"trace[605697438] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"442.6928ms","start":"2026-02-02T16:01:38.21642Z","end":"2026-02-02T16:01:38.659113Z","steps":["trace[605697438] 'process raft request' (duration: 82.600702ms)","trace[605697438] 'compare' (duration: 359.045192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:38.659341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:38.216406Z","time spent":"442.856543ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4318,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:01:38.752511Z","caller":"traceutil/trace.go:171","msg":"trace[2039542854] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"496.052283ms","start":"2026-02-02T16:01:38.256434Z","end":"2026-02-02T16:01:38.752487Z","steps":["trace[2039542854] 'process raft request' (duration: 496.008602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:38.752635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:38.256418Z","time spent":"496.162395ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T16:01:38.75298Z","caller":"traceutil/trace.go:171","msg":"trace[178301448] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"536.434755ms","start":"2026-02-02T16:01:38.216534Z","end":"2026-02-02T16:01:38.752969Z","steps":["trace[178301448] 'process raft request' (duration: 535.80579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:38.753055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:38.216528Z","time spent":"536.487157ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:01:39.308858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.524907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:01:39.308944Z","caller":"traceutil/trace.go:171","msg":"trace[2022801515] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"665.577681ms","start":"2026-02-02T16:01:38.643352Z","end":"2026-02-02T16:01:39.308929Z","steps":["trace[2022801515] 'read index received' (duration: 110.083238ms)","trace[2022801515] 'applied index is now lower than readState.Index' (duration: 555.493313ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:39.311983Z","caller":"traceutil/trace.go:171","msg":"trace[1404299797] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"867.432827ms","start":"2026-02-02T16:01:38.444531Z","end":"2026-02-02T16:01:39.311964Z","steps":["trace[1404299797] 'process raft request' (duration: 575.748092ms)","trace[1404299797] 'compare' (duration: 288.432315ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:39.312116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:38.444506Z","time spent":"867.57438ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T16:01:39.312324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.966004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:39.312356Z","caller":"traceutil/trace.go:171","msg":"trace[112872258] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:525; }","duration":"669.032796ms","start":"2026-02-02T16:01:38.643312Z","end":"2026-02-02T16:01:39.312345Z","steps":["trace[112872258] 'agreement among raft nodes before linearized reading' (duration: 668.957324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:39.312384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:38.643294Z","time spent":"669.084298ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-02T16:01:39.549647Z","caller":"traceutil/trace.go:171","msg":"trace[733472815] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"704.795486ms","start":"2026-02-02T16:01:38.844836Z","end":"2026-02-02T16:01:39.549631Z","steps":["trace[733472815] 'process raft request' (duration: 704.771875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:39.549731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:38.844819Z","time spent":"704.871437ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T16:01:39.549949Z","caller":"traceutil/trace.go:171","msg":"trace[1929210369] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"788.985057ms","start":"2026-02-02T16:01:38.760955Z","end":"2026-02-02T16:01:39.549941Z","steps":["trace[1929210369] 'process raft request' (duration: 780.314533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:39.549989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:38.760939Z","time spent":"789.025287ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:01:39.550109Z","caller":"traceutil/trace.go:171","msg":"trace[12579557] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"779.935404ms","start":"2026-02-02T16:01:38.770169Z","end":"2026-02-02T16:01:39.550105Z","steps":["trace[12579557] 'process raft request' (duration: 779.36324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:39.550135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:38.770153Z","time spent":"779.967445ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:01:39.555392Z","caller":"traceutil/trace.go:171","msg":"trace[943107685] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:541; }","duration":"246.36208ms","start":"2026-02-02T16:01:39.308967Z","end":"2026-02-02T16:01:39.555329Z","steps":["trace[943107685] 'read index received' (duration: 232.312184ms)","trace[943107685] 'applied index is now lower than readState.Index' (duration: 14.048996ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:39.555653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.23171ms","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-02T16:01:39.555705Z","caller":"traceutil/trace.go:171","msg":"trace[1562643393] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:529; }","duration":"512.321382ms","start":"2026-02-02T16:01:39.043375Z","end":"2026-02-02T16:01:39.555696Z","steps":["trace[1562643393] 'agreement among raft nodes before linearized reading' (duration: 512.23249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:39.555739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:39.043364Z","time spent":"512.370313ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-02T16:01:39.555841Z","caller":"traceutil/trace.go:171","msg":"trace[1129287542] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"529.506365ms","start":"2026-02-02T16:01:39.026328Z","end":"2026-02-02T16:01:39.555835Z","steps":["trace[1129287542] 'process raft request' (duration: 528.9112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:39.555887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.346224ms","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":"warn","ts":"2026-02-02T16:01:39.555906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:39.026305Z","time spent":"529.573427ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:01:39.555924Z","caller":"traceutil/trace.go:171","msg":"trace[1422089777] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:529; }","duration":"112.458877ms","start":"2026-02-02T16:01:39.443455Z","end":"2026-02-02T16:01:39.555914Z","steps":["trace[1422089777] 'agreement among raft nodes before linearized reading' (duration: 112.216271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:39.556103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.955868ms","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-02T16:01:39.556147Z","caller":"traceutil/trace.go:171","msg":"trace[1290945129] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:529; }","duration":"313.02331ms","start":"2026-02-02T16:01:39.243116Z","end":"2026-02-02T16:01:39.55614Z","steps":["trace[1290945129] 'agreement among raft nodes before linearized reading' (duration: 312.961509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:39.556177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:39.243087Z","time spent":"313.084321ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-02T16:01:39.557515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.062893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-02T16:01:39.557549Z","caller":"traceutil/trace.go:171","msg":"trace[478093987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:529; }","duration":"496.114484ms","start":"2026-02-02T16:01:39.061427Z","end":"2026-02-02T16:01:39.557541Z","steps":["trace[478093987] 'agreement among raft nodes before linearized reading' (duration: 494.812782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:39.557685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:39.061419Z","time spent":"496.215546ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-02T16:01:42.426039Z","caller":"traceutil/trace.go:171","msg":"trace[2006141416] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"224.39261ms","start":"2026-02-02T16:01:42.20163Z","end":"2026-02-02T16:01:42.426023Z","steps":["trace[2006141416] 'read index received' (duration: 224.267087ms)","trace[2006141416] 'applied index is now lower than readState.Index' (duration: 124.963µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:01:42.426242Z","caller":"traceutil/trace.go:171","msg":"trace[1126928638] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"248.935723ms","start":"2026-02-02T16:01:42.1773Z","end":"2026-02-02T16:01:42.426236Z","steps":["trace[1126928638] 'process raft request' (duration: 248.638066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:42.426376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.730648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:42.426395Z","caller":"traceutil/trace.go:171","msg":"trace[1978211935] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:552; }","duration":"224.78365ms","start":"2026-02-02T16:01:42.201606Z","end":"2026-02-02T16:01:42.42639Z","steps":["trace[1978211935] 'agreement among raft nodes before linearized reading' (duration: 224.739509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:42.426475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.573965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:42.426489Z","caller":"traceutil/trace.go:171","msg":"trace[656161973] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:552; }","duration":"224.588825ms","start":"2026-02-02T16:01:42.201895Z","end":"2026-02-02T16:01:42.426484Z","steps":["trace[656161973] 'agreement among raft nodes before linearized reading' (duration: 224.567044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:42.426797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.37268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:42.426812Z","caller":"traceutil/trace.go:171","msg":"trace[775619926] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:552; }","duration":"185.39494ms","start":"2026-02-02T16:01:42.241413Z","end":"2026-02-02T16:01:42.426808Z","steps":["trace[775619926] 'agreement among raft nodes before linearized reading' (duration: 185.372539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:42.426862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.450571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:42.426874Z","caller":"traceutil/trace.go:171","msg":"trace[393616755] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:552; }","duration":"185.471393ms","start":"2026-02-02T16:01:42.241399Z","end":"2026-02-02T16:01:42.42687Z","steps":["trace[393616755] 'agreement among raft nodes before linearized reading' (duration: 185.452492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:42.426927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.539614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:42.426939Z","caller":"traceutil/trace.go:171","msg":"trace[573415646] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:552; }","duration":"185.572074ms","start":"2026-02-02T16:01:42.241363Z","end":"2026-02-02T16:01:42.426935Z","steps":["trace[573415646] 'agreement among raft nodes before linearized reading' (duration: 185.552634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:42.426991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.073586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:42.427002Z","caller":"traceutil/trace.go:171","msg":"trace[673675021] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:552; }","duration":"225.093147ms","start":"2026-02-02T16:01:42.201906Z","end":"2026-02-02T16:01:42.426999Z","steps":["trace[673675021] 'agreement among raft nodes before linearized reading' (duration: 225.075266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.333034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.976675ms","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-02T16:01:46.333169Z","caller":"traceutil/trace.go:171","msg":"trace[966815816] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:561; }","duration":"186.160919ms","start":"2026-02-02T16:01:46.146988Z","end":"2026-02-02T16:01:46.333149Z","steps":["trace[966815816] 'range keys from in-memory index tree' (duration: 185.814831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.333181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.960641ms","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-02T16:01:46.33324Z","caller":"traceutil/trace.go:171","msg":"trace[769988589] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:561; }","duration":"170.035763ms","start":"2026-02-02T16:01:46.16319Z","end":"2026-02-02T16:01:46.333225Z","steps":["trace[769988589] 'count revisions from in-memory index tree' (duration: 169.93131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.333384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.243397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:46.333402Z","caller":"traceutil/trace.go:171","msg":"trace[1224545754] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:561; }","duration":"170.280598ms","start":"2026-02-02T16:01:46.163116Z","end":"2026-02-02T16:01:46.333397Z","steps":["trace[1224545754] 'range keys from in-memory index tree' (duration: 170.134034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.333493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.257008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:46.333511Z","caller":"traceutil/trace.go:171","msg":"trace[43482392] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:561; }","duration":"170.280819ms","start":"2026-02-02T16:01:46.163225Z","end":"2026-02-02T16:01:46.333505Z","steps":["trace[43482392] 'range keys from in-memory index tree' (duration: 170.231867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.333592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.591951ms","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-02T16:01:46.333614Z","caller":"traceutil/trace.go:171","msg":"trace[2077121609] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:561; }","duration":"158.615931ms","start":"2026-02-02T16:01:46.174988Z","end":"2026-02-02T16:01:46.333604Z","steps":["trace[2077121609] 'count revisions from in-memory index tree' (duration: 158.57179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.333704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.745564ms","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-02T16:01:46.333724Z","caller":"traceutil/trace.go:171","msg":"trace[654244816] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:561; }","duration":"158.777366ms","start":"2026-02-02T16:01:46.174939Z","end":"2026-02-02T16:01:46.333717Z","steps":["trace[654244816] 'range keys from in-memory index tree' (duration: 158.653992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.333791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.754225ms","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-02T16:01:46.333806Z","caller":"traceutil/trace.go:171","msg":"trace[70400239] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:561; }","duration":"158.770845ms","start":"2026-02-02T16:01:46.175031Z","end":"2026-02-02T16:01:46.333801Z","steps":["trace[70400239] 'range keys from in-memory index tree' (duration: 158.734254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.333874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.285756ms","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-02T16:01:46.33389Z","caller":"traceutil/trace.go:171","msg":"trace[804988360] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:561; }","duration":"141.310986ms","start":"2026-02-02T16:01:46.192574Z","end":"2026-02-02T16:01:46.333885Z","steps":["trace[804988360] 'range keys from in-memory index tree' (duration: 141.260875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.333965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.439149ms","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-02T16:01:46.333981Z","caller":"traceutil/trace.go:171","msg":"trace[492803165] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:561; }","duration":"141.47519ms","start":"2026-02-02T16:01:46.192501Z","end":"2026-02-02T16:01:46.333976Z","steps":["trace[492803165] 'range keys from in-memory index tree' (duration: 141.307306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.333978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.69032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-02T16:01:46.334048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.406779ms","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-02T16:01:46.334066Z","caller":"traceutil/trace.go:171","msg":"trace[1433289971] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:561; }","duration":"141.435759ms","start":"2026-02-02T16:01:46.192626Z","end":"2026-02-02T16:01:46.334061Z","steps":["trace[1433289971] 'count revisions from in-memory index tree' (duration: 141.378568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.334131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.34294ms","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-02T16:01:46.334179Z","caller":"traceutil/trace.go:171","msg":"trace[1643342029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"218.405682ms","start":"2026-02-02T16:01:46.115745Z","end":"2026-02-02T16:01:46.334151Z","steps":["trace[1643342029] 'range keys from in-memory index tree' (duration: 218.205176ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:46.334041Z","caller":"traceutil/trace.go:171","msg":"trace[1729756736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"251.774723ms","start":"2026-02-02T16:01:46.082255Z","end":"2026-02-02T16:01:46.33403Z","steps":["trace[1729756736] 'range keys from in-memory index tree' (duration: 251.550657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.334501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.651933ms","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-02T16:01:46.334544Z","caller":"traceutil/trace.go:171","msg":"trace[1285401541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"217.784906ms","start":"2026-02-02T16:01:46.116746Z","end":"2026-02-02T16:01:46.334531Z","steps":["trace[1285401541] 'range keys from in-memory index tree' (duration: 217.580211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.334663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.184109ms","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-02T16:01:46.334686Z","caller":"traceutil/trace.go:171","msg":"trace[716447816] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:561; }","duration":"188.20935ms","start":"2026-02-02T16:01:46.14647Z","end":"2026-02-02T16:01:46.33468Z","steps":["trace[716447816] 'range keys from in-memory index tree' (duration: 188.129378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:46.334768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.678327ms","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-02T16:01:46.334787Z","caller":"traceutil/trace.go:171","msg":"trace[2142897343] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:561; }","duration":"187.715667ms","start":"2026-02-02T16:01:46.147067Z","end":"2026-02-02T16:01:46.334783Z","steps":["trace[2142897343] 'count revisions from in-memory index tree' (duration: 187.643776ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:47.115959Z","caller":"traceutil/trace.go:171","msg":"trace[535770345] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"774.559823ms","start":"2026-02-02T16:01:46.341165Z","end":"2026-02-02T16:01:47.115725Z","steps":["trace[535770345] 'process raft request' (duration: 774.325577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:47.116139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:46.341152Z","time spent":"774.909851ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:01:47.120474Z","caller":"traceutil/trace.go:171","msg":"trace[2056636131] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"775.992349ms","start":"2026-02-02T16:01:46.344474Z","end":"2026-02-02T16:01:47.120466Z","steps":["trace[2056636131] 'process raft request' (duration: 775.750733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:47.120548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:46.344456Z","time spent":"776.05051ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:01:47.120434Z","caller":"traceutil/trace.go:171","msg":"trace[404770014] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"774.121703ms","start":"2026-02-02T16:01:46.346298Z","end":"2026-02-02T16:01:47.12042Z","steps":["trace[404770014] 'process raft request' (duration: 774.054711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:47.122088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:46.346287Z","time spent":"775.751642ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:01:57.81792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.09125ms","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-02T16:01:57.817982Z","caller":"traceutil/trace.go:171","msg":"trace[724062159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"628.180352ms","start":"2026-02-02T16:01:57.189791Z","end":"2026-02-02T16:01:57.817971Z","steps":["trace[724062159] 'range keys from in-memory index tree' (duration: 627.945487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:57.818007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:57.189777Z","time spent":"628.224574ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T16:01:57.818091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.492111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:01:57.818173Z","caller":"traceutil/trace.go:171","msg":"trace[1193514119] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"233.895064ms","start":"2026-02-02T16:01:57.584262Z","end":"2026-02-02T16:01:57.818157Z","steps":["trace[1193514119] 'read index received' (duration: 54.412µs)","trace[1193514119] 'applied index is now lower than readState.Index' (duration: 233.839712ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:57.818304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.041637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:01:57.81833Z","caller":"traceutil/trace.go:171","msg":"trace[1341947151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"234.082588ms","start":"2026-02-02T16:01:57.584236Z","end":"2026-02-02T16:01:57.818319Z","steps":["trace[1341947151] 'agreement among raft nodes before linearized reading' (duration: 234.049387ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:01:57.818432Z","caller":"traceutil/trace.go:171","msg":"trace[478717045] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"614.062335ms","start":"2026-02-02T16:01:57.204349Z","end":"2026-02-02T16:01:57.818411Z","steps":["trace[478717045] 'process raft request' (duration: 274.178434ms)","trace[478717045] 'compare' (duration: 339.176804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:57.818453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.487446ms","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-02T16:01:57.818488Z","caller":"traceutil/trace.go:171","msg":"trace[1700411982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:629; }","duration":"153.541897ms","start":"2026-02-02T16:01:57.664936Z","end":"2026-02-02T16:01:57.818478Z","steps":["trace[1700411982] 'agreement among raft nodes before linearized reading' (duration: 153.425094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:01:57.818486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:57.204333Z","time spent":"614.113577ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:01:58.249534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.436603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:01:58.249606Z","caller":"traceutil/trace.go:171","msg":"trace[938328244] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"336.942528ms","start":"2026-02-02T16:01:57.912652Z","end":"2026-02-02T16:01:58.249594Z","steps":["trace[938328244] 'process raft request' (duration: 29.393783ms)","trace[938328244] 'compare' (duration: 307.364771ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:01:58.249647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:01:57.912633Z","time spent":"336.990739ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:03.141583Z","caller":"traceutil/trace.go:171","msg":"trace[1509628023] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"152.018609ms","start":"2026-02-02T16:02:02.989541Z","end":"2026-02-02T16:02:03.14156Z","steps":["trace[1509628023] 'process raft request' (duration: 151.899846ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:03.346144Z","caller":"traceutil/trace.go:171","msg":"trace[674324020] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"188.775903ms","start":"2026-02-02T16:02:03.15735Z","end":"2026-02-02T16:02:03.346126Z","steps":["trace[674324020] 'read index received' (duration: 151.6563ms)","trace[674324020] 'applied index is now lower than readState.Index' (duration: 37.119093ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:02:03.346206Z","caller":"traceutil/trace.go:171","msg":"trace[1571031002] transaction","detail":"{read_only:false; number_of_response:0; response_revision:661; }","duration":"192.619958ms","start":"2026-02-02T16:02:03.153581Z","end":"2026-02-02T16:02:03.346201Z","steps":["trace[1571031002] 'process raft request' (duration: 155.471944ms)","trace[1571031002] 'compare' (duration: 37.047932ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:03.346335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.971828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:03.346353Z","caller":"traceutil/trace.go:171","msg":"trace[110627400] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:661; }","duration":"189.01526ms","start":"2026-02-02T16:02:03.157331Z","end":"2026-02-02T16:02:03.346346Z","steps":["trace[110627400] 'agreement among raft nodes before linearized reading' (duration: 188.973219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:03.346497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.084536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pxjrp\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-02T16:02:03.346512Z","caller":"traceutil/trace.go:171","msg":"trace[999187256] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pxjrp; range_end:; response_count:1; response_revision:661; }","duration":"164.126428ms","start":"2026-02-02T16:02:03.18238Z","end":"2026-02-02T16:02:03.346507Z","steps":["trace[999187256] 'agreement among raft nodes before linearized reading' (duration: 164.049626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:03.346649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.394457ms","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-02T16:02:03.346663Z","caller":"traceutil/trace.go:171","msg":"trace[717331436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"132.424437ms","start":"2026-02-02T16:02:03.214235Z","end":"2026-02-02T16:02:03.346659Z","steps":["trace[717331436] 'agreement among raft nodes before linearized reading' (duration: 132.385196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:03.683811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.41992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:02:03.683944Z","caller":"traceutil/trace.go:171","msg":"trace[1339458705] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"321.910509ms","start":"2026-02-02T16:02:03.362024Z","end":"2026-02-02T16:02:03.683934Z","steps":["trace[1339458705] 'process raft request' (duration: 321.859147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:03.683987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:03.362017Z","time spent":"321.95541ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18047,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:03.684217Z","caller":"traceutil/trace.go:171","msg":"trace[402926041] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"332.97491ms","start":"2026-02-02T16:02:03.351235Z","end":"2026-02-02T16:02:03.68421Z","steps":["trace[402926041] 'process raft request' (duration: 219.10991ms)","trace[402926041] 'compare' (duration: 113.298837ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:03.684243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:03.351227Z","time spent":"333.001921ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:03.740372Z","caller":"traceutil/trace.go:171","msg":"trace[768728714] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"213.548093ms","start":"2026-02-02T16:02:03.526811Z","end":"2026-02-02T16:02:03.740359Z","steps":["trace[768728714] 'process raft request' (duration: 213.530922ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:03.740552Z","caller":"traceutil/trace.go:171","msg":"trace[1150525922] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"277.726381ms","start":"2026-02-02T16:02:03.462819Z","end":"2026-02-02T16:02:03.740546Z","steps":["trace[1150525922] 'process raft request' (duration: 277.427504ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:03.740675Z","caller":"traceutil/trace.go:171","msg":"trace[931446959] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"240.66665ms","start":"2026-02-02T16:02:03.500004Z","end":"2026-02-02T16:02:03.74067Z","steps":["trace[931446959] 'process raft request' (duration: 240.302891ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:03.740902Z","caller":"traceutil/trace.go:171","msg":"trace[1488523632] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:690; }","duration":"156.878079ms","start":"2026-02-02T16:02:03.584019Z","end":"2026-02-02T16:02:03.740897Z","steps":["trace[1488523632] 'read index received' (duration: 156.875719ms)","trace[1488523632] 'applied index is now lower than readState.Index' (duration: 1.82µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:03.740948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.9209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:03.740969Z","caller":"traceutil/trace.go:171","msg":"trace[251429055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"156.963791ms","start":"2026-02-02T16:02:03.583994Z","end":"2026-02-02T16:02:03.740958Z","steps":["trace[251429055] 'agreement among raft nodes before linearized reading' (duration: 156.92541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:04.609378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.169184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:02:04.609447Z","caller":"traceutil/trace.go:171","msg":"trace[601626590] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"865.248273ms","start":"2026-02-02T16:02:03.744187Z","end":"2026-02-02T16:02:04.609435Z","steps":["trace[601626590] 'read index received' (duration: 81.909395ms)","trace[601626590] 'applied index is now lower than readState.Index' (duration: 783.338168ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:02:04.60963Z","caller":"traceutil/trace.go:171","msg":"trace[2076024523] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"913.868959ms","start":"2026-02-02T16:02:03.695753Z","end":"2026-02-02T16:02:04.609622Z","steps":["trace[2076024523] 'process raft request' (duration: 130.404038ms)","trace[2076024523] 'compare' (duration: 782.746814ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:04.60971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:03.695735Z","time spent":"913.92029ms","remote":"127.0.0.1:51504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:02:04.609848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.656643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:04.609891Z","caller":"traceutil/trace.go:171","msg":"trace[587949166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"865.723975ms","start":"2026-02-02T16:02:03.744159Z","end":"2026-02-02T16:02:04.609883Z","steps":["trace[587949166] 'agreement among raft nodes before linearized reading' (duration: 865.662973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:04.609914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:03.744149Z","time spent":"865.759325ms","remote":"127.0.0.1:51034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T16:02:04.610084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.292937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T16:02:04.610108Z","caller":"traceutil/trace.go:171","msg":"trace[1176678587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:668; }","duration":"759.317517ms","start":"2026-02-02T16:02:03.850785Z","end":"2026-02-02T16:02:04.610103Z","steps":["trace[1176678587] 'agreement among raft nodes before linearized reading' (duration: 759.243355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:04.610125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:03.850746Z","time spent":"759.375899ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T16:02:04.610388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.298054ms","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-02T16:02:04.610417Z","caller":"traceutil/trace.go:171","msg":"trace[1797152578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:668; }","duration":"345.351756ms","start":"2026-02-02T16:02:04.265058Z","end":"2026-02-02T16:02:04.610409Z","steps":["trace[1797152578] 'agreement among raft nodes before linearized reading' (duration: 345.300794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:04.610438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:04.265045Z","time spent":"345.388736ms","remote":"127.0.0.1:51306","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-02T16:02:04.610555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.139868ms","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-02T16:02:04.610581Z","caller":"traceutil/trace.go:171","msg":"trace[621895789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:668; }","duration":"684.185669ms","start":"2026-02-02T16:02:03.926388Z","end":"2026-02-02T16:02:04.610574Z","steps":["trace[621895789] 'agreement among raft nodes before linearized reading' (duration: 684.140948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:04.610602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:03.926378Z","time spent":"684.219009ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-02T16:02:04.610712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.828104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T16:02:04.610732Z","caller":"traceutil/trace.go:171","msg":"trace[620696762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:668; }","duration":"749.860145ms","start":"2026-02-02T16:02:03.860866Z","end":"2026-02-02T16:02:04.610726Z","steps":["trace[620696762] 'agreement among raft nodes before linearized reading' (duration: 749.808223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:04.610748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:03.860859Z","time spent":"749.885175ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-02T16:02:05.000455Z","caller":"traceutil/trace.go:171","msg":"trace[1079599258] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"382.646193ms","start":"2026-02-02T16:02:04.617788Z","end":"2026-02-02T16:02:05.000434Z","steps":["trace[1079599258] 'process raft request' (duration: 382.51012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:05.000753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:04.617772Z","time spent":"382.913529ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:02:05.479289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.858391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:02:05.479476Z","caller":"traceutil/trace.go:171","msg":"trace[1428730148] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:692; }","duration":"856.507209ms","start":"2026-02-02T16:02:04.622955Z","end":"2026-02-02T16:02:05.479462Z","steps":["trace[1428730148] 'read index received' (duration: 378.403399ms)","trace[1428730148] 'applied index is now lower than readState.Index' (duration: 478.10297ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:05.479564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.603951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:05.479589Z","caller":"traceutil/trace.go:171","msg":"trace[90180079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"856.628692ms","start":"2026-02-02T16:02:04.622952Z","end":"2026-02-02T16:02:05.479581Z","steps":["trace[90180079] 'agreement among raft nodes before linearized reading' (duration: 856.574321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:05.479619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:04.622946Z","time spent":"856.665533ms","remote":"127.0.0.1:51024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T16:02:05.479899Z","caller":"traceutil/trace.go:171","msg":"trace[881554216] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"861.963663ms","start":"2026-02-02T16:02:04.617923Z","end":"2026-02-02T16:02:05.479886Z","steps":["trace[881554216] 'process raft request' (duration: 549.440496ms)","trace[881554216] 'compare' (duration: 311.550064ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:05.479958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:04.617918Z","time spent":"862.010465ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:05.480119Z","caller":"traceutil/trace.go:171","msg":"trace[1475089494] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"861.490781ms","start":"2026-02-02T16:02:04.618617Z","end":"2026-02-02T16:02:05.480107Z","steps":["trace[1475089494] 'process raft request' (duration: 860.764653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:05.480169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:04.61861Z","time spent":"861.532463ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:05.480325Z","caller":"traceutil/trace.go:171","msg":"trace[2138836706] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"861.194924ms","start":"2026-02-02T16:02:04.619122Z","end":"2026-02-02T16:02:05.480317Z","steps":["trace[2138836706] 'process raft request' (duration: 860.311532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:05.480372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:04.619117Z","time spent":"861.230675ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:13.789533Z","caller":"traceutil/trace.go:171","msg":"trace[501378629] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"274.712007ms","start":"2026-02-02T16:02:13.5148Z","end":"2026-02-02T16:02:13.789512Z","steps":["trace[501378629] 'read index received' (duration: 274.570494ms)","trace[501378629] 'applied index is now lower than readState.Index' (duration: 140.733µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:02:13.789636Z","caller":"traceutil/trace.go:171","msg":"trace[1237074088] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"333.596676ms","start":"2026-02-02T16:02:13.45603Z","end":"2026-02-02T16:02:13.789627Z","steps":["trace[1237074088] 'process raft request' (duration: 333.35273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:13.789728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:13.456012Z","time spent":"333.646707ms","remote":"127.0.0.1:51188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4726,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:02:13.790185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.382274ms","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-02T16:02:13.790215Z","caller":"traceutil/trace.go:171","msg":"trace[1807306301] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"275.446665ms","start":"2026-02-02T16:02:13.514759Z","end":"2026-02-02T16:02:13.790206Z","steps":["trace[1807306301] 'agreement among raft nodes before linearized reading' (duration: 275.375934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:13.790494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.469544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T16:02:13.790519Z","caller":"traceutil/trace.go:171","msg":"trace[425936483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:728; }","duration":"268.515385ms","start":"2026-02-02T16:02:13.521996Z","end":"2026-02-02T16:02:13.790512Z","steps":["trace[425936483] 'agreement among raft nodes before linearized reading' (duration: 268.450953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:13.790523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.326929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T16:02:13.790557Z","caller":"traceutil/trace.go:171","msg":"trace[909325391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"272.38352ms","start":"2026-02-02T16:02:13.518164Z","end":"2026-02-02T16:02:13.790547Z","steps":["trace[909325391] 'agreement among raft nodes before linearized reading' (duration: 271.629821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:14.025301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.639854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:02:14.025377Z","caller":"traceutil/trace.go:171","msg":"trace[1891676892] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"217.063719ms","start":"2026-02-02T16:02:13.808301Z","end":"2026-02-02T16:02:14.025365Z","steps":["trace[1891676892] 'process raft request' (duration: 75.314472ms)","trace[1891676892] 'compare' (duration: 141.500671ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:02:14.118844Z","caller":"traceutil/trace.go:171","msg":"trace[1519427243] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"306.558761ms","start":"2026-02-02T16:02:13.812271Z","end":"2026-02-02T16:02:14.11883Z","steps":["trace[1519427243] 'process raft request' (duration: 306.53517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:14.118942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:13.812264Z","time spent":"306.622072ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:14.118921Z","caller":"traceutil/trace.go:171","msg":"trace[38236849] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"309.179215ms","start":"2026-02-02T16:02:13.809689Z","end":"2026-02-02T16:02:14.118868Z","steps":["trace[38236849] 'process raft request' (duration: 309.027231ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:14.119072Z","caller":"traceutil/trace.go:171","msg":"trace[197917095] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"308.181401ms","start":"2026-02-02T16:02:13.810882Z","end":"2026-02-02T16:02:14.119064Z","steps":["trace[197917095] 'process raft request' (duration: 307.903194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:14.119102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:13.810875Z","time spent":"308.211051ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:02:14.119122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:13.809681Z","time spent":"309.325348ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:14.271609Z","caller":"traceutil/trace.go:171","msg":"trace[1419301098] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"381.116054ms","start":"2026-02-02T16:02:13.890473Z","end":"2026-02-02T16:02:14.271589Z","steps":["trace[1419301098] 'process raft request' (duration: 326.44895ms)","trace[1419301098] 'compare' (duration: 54.595723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:14.27171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:13.890457Z","time spent":"381.200436ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:28.250876Z","caller":"traceutil/trace.go:171","msg":"trace[1863603249] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"179.433045ms","start":"2026-02-02T16:02:28.071422Z","end":"2026-02-02T16:02:28.250855Z","steps":["trace[1863603249] 'process raft request' (duration: 179.081476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:29.081355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.598823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:02:29.081736Z","caller":"traceutil/trace.go:171","msg":"trace[890260566] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"823.174277ms","start":"2026-02-02T16:02:28.258537Z","end":"2026-02-02T16:02:29.081711Z","steps":["trace[890260566] 'process raft request' (duration: 374.144213ms)","trace[890260566] 'compare' (duration: 446.65138ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:29.081968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:28.258522Z","time spent":"823.400253ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:29.131983Z","caller":"traceutil/trace.go:171","msg":"trace[1449723557] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"873.192088ms","start":"2026-02-02T16:02:28.258771Z","end":"2026-02-02T16:02:29.131963Z","steps":["trace[1449723557] 'process raft request' (duration: 872.972723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:29.132195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:28.258759Z","time spent":"873.364163ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:29.133921Z","caller":"traceutil/trace.go:171","msg":"trace[1718417571] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:803; }","duration":"545.678955ms","start":"2026-02-02T16:02:28.587543Z","end":"2026-02-02T16:02:29.133222Z","steps":["trace[1718417571] 'read index received' (duration: 45.149529ms)","trace[1718417571] 'applied index is now lower than readState.Index' (duration: 500.526625ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:02:29.13422Z","caller":"traceutil/trace.go:171","msg":"trace[1443255315] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"875.379177ms","start":"2026-02-02T16:02:28.258826Z","end":"2026-02-02T16:02:29.134205Z","steps":["trace[1443255315] 'process raft request' (duration: 873.099165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:29.134409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:28.258821Z","time spent":"875.47991ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:02:29.134965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.415721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:29.138993Z","caller":"traceutil/trace.go:171","msg":"trace[1593103904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"551.43448ms","start":"2026-02-02T16:02:28.587538Z","end":"2026-02-02T16:02:29.138973Z","steps":["trace[1593103904] 'agreement among raft nodes before linearized reading' (duration: 547.37815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:29.13907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:28.587469Z","time spent":"551.589914ms","remote":"127.0.0.1:51024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T16:02:29.555478Z","caller":"traceutil/trace.go:171","msg":"trace[163159685] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"415.353936ms","start":"2026-02-02T16:02:29.140098Z","end":"2026-02-02T16:02:29.555452Z","steps":["trace[163159685] 'read index received' (duration: 380.641959ms)","trace[163159685] 'applied index is now lower than readState.Index' (duration: 34.710707ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:29.555629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.50621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:29.55566Z","caller":"traceutil/trace.go:171","msg":"trace[1106781723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"415.568471ms","start":"2026-02-02T16:02:29.140083Z","end":"2026-02-02T16:02:29.555651Z","steps":["trace[1106781723] 'agreement among raft nodes before linearized reading' (duration: 415.455588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:29.555689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:29.140075Z","time spent":"415.607163ms","remote":"127.0.0.1:51034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T16:02:29.555774Z","caller":"traceutil/trace.go:171","msg":"trace[2035918181] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"416.687712ms","start":"2026-02-02T16:02:29.139066Z","end":"2026-02-02T16:02:29.555754Z","steps":["trace[2035918181] 'process raft request' (duration: 381.734119ms)","trace[2035918181] 'compare' (duration: 34.018648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:02:29.555883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:29.139028Z","time spent":"416.796815ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:02:41.962407Z","caller":"traceutil/trace.go:171","msg":"trace[528241438] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"357.553476ms","start":"2026-02-02T16:02:41.604835Z","end":"2026-02-02T16:02:41.962388Z","steps":["trace[528241438] 'process raft request' (duration: 357.417483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:41.96277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:41.604817Z","time spent":"357.900815ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:02:47.438865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039744945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-02T16:02:47.438914Z","caller":"traceutil/trace.go:171","msg":"trace[1699003907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:880; }","duration":"1.039828508s","start":"2026-02-02T16:02:46.399072Z","end":"2026-02-02T16:02:47.438901Z","steps":["trace[1699003907] 'range keys from in-memory index tree' (duration: 1.039602472s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:47.438893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.872796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:02:47.438977Z","caller":"traceutil/trace.go:171","msg":"trace[2065552587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"850.995109ms","start":"2026-02-02T16:02:46.587964Z","end":"2026-02-02T16:02:47.438959Z","steps":["trace[2065552587] 'range keys from in-memory index tree' (duration: 850.818984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:47.439038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:46.58795Z","time spent":"851.059231ms","remote":"127.0.0.1:51024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T16:02:47.439055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.390164ms","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-02T16:02:47.439074Z","caller":"traceutil/trace.go:171","msg":"trace[1772256174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:880; }","duration":"289.430755ms","start":"2026-02-02T16:02:47.149638Z","end":"2026-02-02T16:02:47.439068Z","steps":["trace[1772256174] 'range keys from in-memory index tree' (duration: 289.326592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:47.43944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.677535ms","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-02T16:02:47.439482Z","caller":"traceutil/trace.go:171","msg":"trace[396344121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"238.755977ms","start":"2026-02-02T16:02:47.200713Z","end":"2026-02-02T16:02:47.439469Z","steps":["trace[396344121] 'range keys from in-memory index tree' (duration: 238.556412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:47.438937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:46.399055Z","time spent":"1.03987692s","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T16:02:47.439456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.454013ms","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-02T16:02:47.439616Z","caller":"traceutil/trace.go:171","msg":"trace[446790473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"230.625617ms","start":"2026-02-02T16:02:47.208983Z","end":"2026-02-02T16:02:47.439609Z","steps":["trace[446790473] 'range keys from in-memory index tree' (duration: 230.398511ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:47.619173Z","caller":"traceutil/trace.go:171","msg":"trace[1275998974] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"169.367283ms","start":"2026-02-02T16:02:47.449784Z","end":"2026-02-02T16:02:47.619151Z","steps":["trace[1275998974] 'process raft request' (duration: 169.205309ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:47.628404Z","caller":"traceutil/trace.go:171","msg":"trace[1630132174] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"173.263599ms","start":"2026-02-02T16:02:47.455127Z","end":"2026-02-02T16:02:47.628391Z","steps":["trace[1630132174] 'process raft request' (duration: 173.233338ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:47.628726Z","caller":"traceutil/trace.go:171","msg":"trace[1785005827] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"178.4788ms","start":"2026-02-02T16:02:47.450236Z","end":"2026-02-02T16:02:47.628715Z","steps":["trace[1785005827] 'process raft request' (duration: 178.035938ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:47.628842Z","caller":"traceutil/trace.go:171","msg":"trace[551750003] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"175.694544ms","start":"2026-02-02T16:02:47.45314Z","end":"2026-02-02T16:02:47.628835Z","steps":["trace[551750003] 'process raft request' (duration: 175.19629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:51.879916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.961464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:02:51.880022Z","caller":"traceutil/trace.go:171","msg":"trace[1585037610] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"151.630084ms","start":"2026-02-02T16:02:51.728382Z","end":"2026-02-02T16:02:51.880013Z","steps":["trace[1585037610] 'process raft request' (duration: 151.612774ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:51.880209Z","caller":"traceutil/trace.go:171","msg":"trace[1546916870] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"153.980558ms","start":"2026-02-02T16:02:51.726223Z","end":"2026-02-02T16:02:51.880204Z","steps":["trace[1546916870] 'process raft request' (duration: 38.682745ms)","trace[1546916870] 'compare' (duration: 114.762389ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:02:51.88027Z","caller":"traceutil/trace.go:171","msg":"trace[858225519] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"151.947313ms","start":"2026-02-02T16:02:51.728319Z","end":"2026-02-02T16:02:51.880266Z","steps":["trace[858225519] 'process raft request' (duration: 151.653815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:52.10275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.85656ms","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-02T16:02:52.102805Z","caller":"traceutil/trace.go:171","msg":"trace[1171414621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"111.938733ms","start":"2026-02-02T16:02:51.990852Z","end":"2026-02-02T16:02:52.102791Z","steps":["trace[1171414621] 'range keys from in-memory index tree' (duration: 111.747267ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:02:55.185656Z","caller":"traceutil/trace.go:171","msg":"trace[863657214] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"322.303364ms","start":"2026-02-02T16:02:54.863336Z","end":"2026-02-02T16:02:55.185639Z","steps":["trace[863657214] 'process raft request' (duration: 322.200471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:02:55.185755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:02:54.863322Z","time spent":"322.382776ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:02:56.149639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.247979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:02:56.15031Z","caller":"traceutil/trace.go:171","msg":"trace[1701439535] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"212.783336ms","start":"2026-02-02T16:02:55.937507Z","end":"2026-02-02T16:02:56.15029Z","steps":["trace[1701439535] 'process raft request' (duration: 105.826698ms)","trace[1701439535] 'compare' (duration: 106.113565ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:02:56.150504Z","caller":"traceutil/trace.go:171","msg":"trace[2056172527] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"211.918143ms","start":"2026-02-02T16:02:55.938573Z","end":"2026-02-02T16:02:56.150491Z","steps":["trace[2056172527] 'process raft request' (duration: 211.194743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:03:02.519222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.646871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-02T16:03:02.519247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.29883ms","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-02T16:03:02.519303Z","caller":"traceutil/trace.go:171","msg":"trace[1705844386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"303.388323ms","start":"2026-02-02T16:03:02.215901Z","end":"2026-02-02T16:03:02.519289Z","steps":["trace[1705844386] 'range keys from in-memory index tree' (duration: 303.188058ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:03:02.519315Z","caller":"traceutil/trace.go:171","msg":"trace[385827580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"543.766424ms","start":"2026-02-02T16:03:01.97552Z","end":"2026-02-02T16:03:02.519286Z","steps":["trace[385827580] 'range keys from in-memory index tree' (duration: 543.427165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:03:02.519324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:03:02.215886Z","time spent":"303.434124ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T16:03:02.519349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:03:01.975482Z","time spent":"543.859586ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T16:03:02.519441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.736411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T16:03:02.519462Z","caller":"traceutil/trace.go:171","msg":"trace[1391229929] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:921; }","duration":"527.780262ms","start":"2026-02-02T16:03:01.991673Z","end":"2026-02-02T16:03:02.519454Z","steps":["trace[1391229929] 'count revisions from in-memory index tree' (duration: 527.601318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:03:02.519475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.611065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-02T16:03:02.51948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:03:01.991662Z","time spent":"527.812573ms","remote":"127.0.0.1:51290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-02-02T16:03:02.519492Z","caller":"traceutil/trace.go:171","msg":"trace[633298253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"281.660766ms","start":"2026-02-02T16:03:02.237824Z","end":"2026-02-02T16:03:02.519485Z","steps":["trace[633298253] 'range keys from in-memory index tree' (duration: 281.45331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:03:02.519593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.582403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-02T16:03:02.519608Z","caller":"traceutil/trace.go:171","msg":"trace[241605979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"268.618163ms","start":"2026-02-02T16:03:02.250984Z","end":"2026-02-02T16:03:02.519602Z","steps":["trace[241605979] 'range keys from in-memory index tree' (duration: 268.50344ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:03:02.770076Z","caller":"traceutil/trace.go:171","msg":"trace[661578577] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:959; }","duration":"183.925827ms","start":"2026-02-02T16:03:02.586133Z","end":"2026-02-02T16:03:02.770059Z","steps":["trace[661578577] 'read index received' (duration: 7.988946ms)","trace[661578577] 'applied index is now lower than readState.Index' (duration: 175.935891ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:03:02.770174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.027889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:03:02.770193Z","caller":"traceutil/trace.go:171","msg":"trace[2007015510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"184.087691ms","start":"2026-02-02T16:03:02.586099Z","end":"2026-02-02T16:03:02.770187Z","steps":["trace[2007015510] 'agreement among raft nodes before linearized reading' (duration: 184.022979ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:03:02.770421Z","caller":"traceutil/trace.go:171","msg":"trace[1616726572] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"243.722661ms","start":"2026-02-02T16:03:02.52669Z","end":"2026-02-02T16:03:02.770413Z","steps":["trace[1616726572] 'process raft request' (duration: 153.30262ms)","trace[1616726572] 'compare' (duration: 89.924598ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:03:02.770505Z","caller":"traceutil/trace.go:171","msg":"trace[313306130] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"242.974411ms","start":"2026-02-02T16:03:02.527524Z","end":"2026-02-02T16:03:02.770499Z","steps":["trace[313306130] 'process raft request' (duration: 242.485037ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:03:02.770575Z","caller":"traceutil/trace.go:171","msg":"trace[1100792566] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"201.376218ms","start":"2026-02-02T16:03:02.569193Z","end":"2026-02-02T16:03:02.77057Z","steps":["trace[1100792566] 'process raft request' (duration: 200.841733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:03:14.926904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.307183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:03:14.928092Z","caller":"traceutil/trace.go:171","msg":"trace[1000838552] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"256.138757ms","start":"2026-02-02T16:03:14.67193Z","end":"2026-02-02T16:03:14.928068Z","steps":["trace[1000838552] 'process raft request' (duration: 42.58567ms)","trace[1000838552] 'compare' (duration: 212.162329ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:03:15.64765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.572846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T16:03:15.648215Z","caller":"traceutil/trace.go:171","msg":"trace[794283464] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"711.549504ms","start":"2026-02-02T16:03:14.936638Z","end":"2026-02-02T16:03:15.648188Z","steps":["trace[794283464] 'process raft request' (duration: 223.351881ms)","trace[794283464] 'compare' (duration: 487.300419ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:03:15.648447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:03:14.936609Z","time spent":"711.78037ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:03:15.648486Z","caller":"traceutil/trace.go:171","msg":"trace[1220211843] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"711.710228ms","start":"2026-02-02T16:03:14.936756Z","end":"2026-02-02T16:03:15.648467Z","steps":["trace[1220211843] 'process raft request' (duration: 711.344409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:03:15.648934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:03:14.936751Z","time spent":"712.14403ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:03:15.648952Z","caller":"traceutil/trace.go:171","msg":"trace[1681302614] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"711.802061ms","start":"2026-02-02T16:03:14.937136Z","end":"2026-02-02T16:03:15.648938Z","steps":["trace[1681302614] 'process raft request' (duration: 711.263177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:03:15.649254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:03:14.937125Z","time spent":"712.094019ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:03:15.649678Z","caller":"traceutil/trace.go:171","msg":"trace[385130824] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"672.420458ms","start":"2026-02-02T16:03:14.977208Z","end":"2026-02-02T16:03:15.649629Z","steps":["trace[385130824] 'process raft request' (duration: 671.467252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:03:15.649821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:03:14.977192Z","time spent":"672.595762ms","remote":"127.0.0.1:51188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5727,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T16:05:42.945894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477303438891470,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T16:05:43.329488Z","caller":"traceutil/trace.go:171","msg":"trace[1423126955] transaction","detail":"{read_only:false; response_revision:1617; number_of_response:1; }","duration":"968.104537ms","start":"2026-02-02T16:05:42.361359Z","end":"2026-02-02T16:05:43.329463Z","steps":["trace[1423126955] 'process raft request' (duration: 967.975543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:05:43.32968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:05:42.361335Z","time spent":"968.26376ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T16:05:43.3304Z","caller":"traceutil/trace.go:171","msg":"trace[991656452] linearizableReadLoop","detail":"{readStateIndex:1688; appliedIndex:1687; }","duration":"884.823694ms","start":"2026-02-02T16:05:42.445563Z","end":"2026-02-02T16:05:43.330387Z","steps":["trace[991656452] 'read index received' (duration: 883.661742ms)","trace[991656452] 'applied index is now lower than readState.Index' (duration: 1.160682ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T16:05:43.330626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.05659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T16:05:43.330974Z","caller":"traceutil/trace.go:171","msg":"trace[540201431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1617; }","duration":"885.45167ms","start":"2026-02-02T16:05:42.445511Z","end":"2026-02-02T16:05:43.330963Z","steps":["trace[540201431] 'agreement among raft nodes before linearized reading' (duration: 884.969337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:05:43.331057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:05:42.44549Z","time spent":"885.555824ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T16:05:43.330798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.714941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T16:05:43.331297Z","caller":"traceutil/trace.go:171","msg":"trace[775904385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1617; }","duration":"885.231414ms","start":"2026-02-02T16:05:42.446054Z","end":"2026-02-02T16:05:43.331285Z","steps":["trace[775904385] 'agreement among raft nodes before linearized reading' (duration: 884.684089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:05:43.331397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:05:42.446044Z","time spent":"885.341437ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T16:05:43.330859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.836215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-02T16:05:43.331828Z","caller":"traceutil/trace.go:171","msg":"trace[293377766] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1617; }","duration":"789.799332ms","start":"2026-02-02T16:05:42.542014Z","end":"2026-02-02T16:05:43.331813Z","steps":["trace[293377766] 'agreement among raft nodes before linearized reading' (duration: 788.801184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:05:43.331896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:05:42.541877Z","time spent":"790.004926ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T16:05:43.33092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.924749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-02T16:05:43.33244Z","caller":"traceutil/trace.go:171","msg":"trace[625463155] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1617; }","duration":"832.406129ms","start":"2026-02-02T16:05:42.499967Z","end":"2026-02-02T16:05:43.332373Z","steps":["trace[625463155] 'agreement among raft nodes before linearized reading' (duration: 830.907828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:05:43.332532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T16:05:42.499954Z","time spent":"832.563234ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-02T16:06:17.838915Z","caller":"traceutil/trace.go:171","msg":"trace[2027957703] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"279.34125ms","start":"2026-02-02T16:06:17.55955Z","end":"2026-02-02T16:06:17.838892Z","steps":["trace[2027957703] 'process raft request' (duration: 279.209187ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:06:17.863242Z","caller":"traceutil/trace.go:171","msg":"trace[1597082657] linearizableReadLoop","detail":"{readStateIndex:1870; appliedIndex:1868; }","duration":"276.852713ms","start":"2026-02-02T16:06:17.586371Z","end":"2026-02-02T16:06:17.863224Z","steps":["trace[1597082657] 'read index received' (duration: 253.305343ms)","trace[1597082657] 'applied index is now lower than readState.Index' (duration: 23.5466ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T16:06:17.863346Z","caller":"traceutil/trace.go:171","msg":"trace[766922002] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"298.945983ms","start":"2026-02-02T16:06:17.564389Z","end":"2026-02-02T16:06:17.863335Z","steps":["trace[766922002] 'process raft request' (duration: 298.709176ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T16:06:17.863509Z","caller":"traceutil/trace.go:171","msg":"trace[1018032521] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"299.067136ms","start":"2026-02-02T16:06:17.564435Z","end":"2026-02-02T16:06:17.863502Z","steps":["trace[1018032521] 'process raft request' (duration: 298.760658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:06:17.863693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.265425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T16:06:17.863726Z","caller":"traceutil/trace.go:171","msg":"trace[1563840731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1791; }","duration":"277.405328ms","start":"2026-02-02T16:06:17.586313Z","end":"2026-02-02T16:06:17.863718Z","steps":["trace[1563840731] 'agreement among raft nodes before linearized reading' (duration: 277.266494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T16:06:17.864028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.642962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-02T16:06:17.864097Z","caller":"traceutil/trace.go:171","msg":"trace[87381224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1791; }","duration":"186.792456ms","start":"2026-02-02T16:06:17.677288Z","end":"2026-02-02T16:06:17.86408Z","steps":["trace[87381224] 'agreement among raft nodes before linearized reading' (duration: 186.595991ms)"],"step_count":1}