{"level":"warn","ts":"2026-01-26T15:34:18.156849Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-26T15:34:18.158108Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-26T15:34:18.158214Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-26T15:34:18.158246Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-01-26T15:34:18.158291Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-26T15:34:18.159483Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-01-26T15:34:18.15984Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-26T15:34:18.174042Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.39272ms"} {"level":"info","ts":"2026-01-26T15:34:18.18476Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-01-26T15:34:18.184949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-01-26T15:34:18.185035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-01-26T15:34:18.185107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-26T15:34:18.185169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-01-26T15:34:18.185258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-01-26T15:34:18.193569Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-26T15:34:18.195365Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-26T15:34:18.196652Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-01-26T15:34:18.198746Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-26T15:34:18.199213Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-26T15:34:18.199418Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-26T15:34:18.199502Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-26T15:34:18.199568Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-26T15:34:18.206077Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-26T15:34:18.206311Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-26T15:34:18.206493Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-26T15:34:18.207179Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-01-26T15:34:18.207295Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-01-26T15:34:18.207576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-01-26T15:34:18.208364Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-01-26T15:34:18.688577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-01-26T15:34:18.68875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-01-26T15:34:18.688802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-01-26T15:34:18.688858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-01-26T15:34:18.688888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-01-26T15:34:18.688939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-01-26T15:34:18.688976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-01-26T15:34:18.717915Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-26T15:34:18.721589Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-26T15:34:18.721601Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-01-26T15:34:18.721813Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-26T15:34:18.722237Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-26T15:34:18.722252Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-26T15:34:18.723822Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-26T15:34:18.72435Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-01-26T15:34:18.737372Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-01-26T15:34:18.737478Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-26T15:34:18.7375Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-26T15:34:38.031008Z","caller":"traceutil/trace.go:171","msg":"trace[382629728] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"236.847659ms","start":"2026-01-26T15:34:37.794142Z","end":"2026-01-26T15:34:38.030989Z","steps":["trace[382629728] 'read index received' (duration: 236.698585ms)","trace[382629728] 'applied index is now lower than readState.Index' (duration: 147.964µs)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:34:38.031127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.970271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-01-26T15:34:38.031169Z","caller":"traceutil/trace.go:171","msg":"trace[290758964] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:413; }","duration":"237.062364ms","start":"2026-01-26T15:34:37.794099Z","end":"2026-01-26T15:34:38.031162Z","steps":["trace[290758964] 'agreement among raft nodes before linearized reading' (duration: 236.973382ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:34:38.031308Z","caller":"traceutil/trace.go:171","msg":"trace[96862187] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"311.996934ms","start":"2026-01-26T15:34:37.719285Z","end":"2026-01-26T15:34:38.031282Z","steps":["trace[96862187] 'process raft request' (duration: 311.567794ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:38.031971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:37.719274Z","time spent":"312.095056ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:34:38.719007Z","caller":"traceutil/trace.go:171","msg":"trace[741139985] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"178.272636ms","start":"2026-01-26T15:34:38.540683Z","end":"2026-01-26T15:34:38.718956Z","steps":["trace[741139985] 'read index received' (duration: 177.983809ms)","trace[741139985] 'applied index is now lower than readState.Index' (duration: 287.417µs)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:34:38.719245Z","caller":"traceutil/trace.go:171","msg":"trace[1074549498] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"682.150959ms","start":"2026-01-26T15:34:38.037073Z","end":"2026-01-26T15:34:38.719224Z","steps":["trace[1074549498] 'process raft request' (duration: 681.72865ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:38.719325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.629154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:38.719934Z","caller":"traceutil/trace.go:171","msg":"trace[695203327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"179.275439ms","start":"2026-01-26T15:34:38.540643Z","end":"2026-01-26T15:34:38.719919Z","steps":["trace[695203327] 'agreement among raft nodes before linearized reading' (duration: 178.647715ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:38.720639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:38.037056Z","time spent":"682.57498ms","remote":"127.0.0.1:39490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:34:49.988534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.492416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-01-26T15:34:49.988587Z","caller":"traceutil/trace.go:171","msg":"trace[147531640] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:480; }","duration":"212.593699ms","start":"2026-01-26T15:34:49.775982Z","end":"2026-01-26T15:34:49.988576Z","steps":["trace[147531640] 'range keys from in-memory index tree' (duration: 212.423294ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:34:50.876384Z","caller":"traceutil/trace.go:171","msg":"trace[1289490795] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"177.158501ms","start":"2026-01-26T15:34:50.699209Z","end":"2026-01-26T15:34:50.876367Z","steps":["trace[1289490795] 'process raft request' (duration: 167.083498ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:34:51.373247Z","caller":"traceutil/trace.go:171","msg":"trace[2146000591] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:515; }","duration":"513.196829ms","start":"2026-01-26T15:34:50.860027Z","end":"2026-01-26T15:34:51.373224Z","steps":["trace[2146000591] 'read index received' (duration: 6.269504ms)","trace[2146000591] 'applied index is now lower than readState.Index' (duration: 506.926235ms)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:34:51.373497Z","caller":"traceutil/trace.go:171","msg":"trace[213163586] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"549.424456ms","start":"2026-01-26T15:34:50.824065Z","end":"2026-01-26T15:34:51.373489Z","steps":["trace[213163586] 'process raft request' (duration: 548.971365ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:51.373554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:50.82405Z","time spent":"549.466177ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-26T15:34:51.373701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.668831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-26T15:34:51.373718Z","caller":"traceutil/trace.go:171","msg":"trace[36416313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:499; }","duration":"513.712151ms","start":"2026-01-26T15:34:50.860001Z","end":"2026-01-26T15:34:51.373713Z","steps":["trace[36416313] 'agreement among raft nodes before linearized reading' (duration: 513.65416ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:51.373743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:50.859988Z","time spent":"513.750952ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-26T15:34:51.644659Z","caller":"traceutil/trace.go:171","msg":"trace[1720078658] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"419.142088ms","start":"2026-01-26T15:34:51.225502Z","end":"2026-01-26T15:34:51.644645Z","steps":["trace[1720078658] 'process raft request' (duration: 419.121087ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:51.644749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.225484Z","time spent":"419.22056ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-26T15:34:51.64494Z","caller":"traceutil/trace.go:171","msg":"trace[1806389069] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"640.363555ms","start":"2026-01-26T15:34:51.00457Z","end":"2026-01-26T15:34:51.644933Z","steps":["trace[1806389069] 'process raft request' (duration: 639.934585ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:51.644982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.004553Z","time spent":"640.403226ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:34:51.645112Z","caller":"traceutil/trace.go:171","msg":"trace[1182752541] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"620.19166ms","start":"2026-01-26T15:34:51.024908Z","end":"2026-01-26T15:34:51.6451Z","steps":["trace[1182752541] 'process raft request' (duration: 619.687549ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:51.645188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.024889Z","time spent":"620.283382ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-26T15:34:51.645504Z","caller":"traceutil/trace.go:171","msg":"trace[349121153] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:520; }","duration":"272.175865ms","start":"2026-01-26T15:34:51.37329Z","end":"2026-01-26T15:34:51.645466Z","steps":["trace[349121153] 'read index received' (duration: 272.172515ms)","trace[349121153] 'applied index is now lower than readState.Index' (duration: 2.73µs)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:34:51.645559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.076058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-26T15:34:51.645577Z","caller":"traceutil/trace.go:171","msg":"trace[1786655018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:502; }","duration":"575.129779ms","start":"2026-01-26T15:34:51.070442Z","end":"2026-01-26T15:34:51.645572Z","steps":["trace[1786655018] 'agreement among raft nodes before linearized reading' (duration: 575.089718ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:51.645596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.070384Z","time spent":"575.208902ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-26T15:34:51.645678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.009767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-26T15:34:51.645694Z","caller":"traceutil/trace.go:171","msg":"trace[1545483803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"708.051359ms","start":"2026-01-26T15:34:50.937638Z","end":"2026-01-26T15:34:51.645689Z","steps":["trace[1545483803] 'agreement among raft nodes before linearized reading' (duration: 708.023798ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:51.645709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:50.937621Z","time spent":"708.084619ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-26T15:34:51.645818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.841411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-sdzph\" ","response":"range_response_count:1 size:4822"} {"level":"info","ts":"2026-01-26T15:34:51.645835Z","caller":"traceutil/trace.go:171","msg":"trace[409806993] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-sdzph; range_end:; response_count:1; response_revision:502; }","duration":"761.881121ms","start":"2026-01-26T15:34:50.883949Z","end":"2026-01-26T15:34:51.64583Z","steps":["trace[409806993] 'agreement among raft nodes before linearized reading' (duration: 761.813539ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:51.64585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:50.883937Z","time spent":"761.908891ms","remote":"127.0.0.1:39490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4844,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-sdzph\" "} {"level":"info","ts":"2026-01-26T15:34:52.034037Z","caller":"traceutil/trace.go:171","msg":"trace[1298798822] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"594.083106ms","start":"2026-01-26T15:34:51.439941Z","end":"2026-01-26T15:34:52.034024Z","steps":["trace[1298798822] 'process raft request' (duration: 594.056975ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:52.03413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.439926Z","time spent":"594.157327ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-26T15:34:52.034326Z","caller":"traceutil/trace.go:171","msg":"trace[883521752] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"656.24809ms","start":"2026-01-26T15:34:51.378071Z","end":"2026-01-26T15:34:52.034319Z","steps":["trace[883521752] 'process raft request' (duration: 655.80856ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:52.034371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.378058Z","time spent":"656.288201ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:34:52.034498Z","caller":"traceutil/trace.go:171","msg":"trace[781140474] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"647.798805ms","start":"2026-01-26T15:34:51.386694Z","end":"2026-01-26T15:34:52.034493Z","steps":["trace[781140474] 'process raft request' (duration: 647.272313ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:52.034532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.386677Z","time spent":"647.835677ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:34:52.034795Z","caller":"traceutil/trace.go:171","msg":"trace[483819702] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:523; }","duration":"389.266446ms","start":"2026-01-26T15:34:51.645521Z","end":"2026-01-26T15:34:52.034787Z","steps":["trace[483819702] 'read index received' (duration: 389.263686ms)","trace[483819702] 'applied index is now lower than readState.Index' (duration: 2.18µs)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:34:52.03484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.513481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:52.034858Z","caller":"traceutil/trace.go:171","msg":"trace[600452791] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:505; }","duration":"408.595963ms","start":"2026-01-26T15:34:51.626257Z","end":"2026-01-26T15:34:52.034853Z","steps":["trace[600452791] 'agreement among raft nodes before linearized reading' (duration: 408.564942ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:52.034876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.626245Z","time spent":"408.625523ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-26T15:34:52.03495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.09047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:52.034987Z","caller":"traceutil/trace.go:171","msg":"trace[1891632237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"495.150611ms","start":"2026-01-26T15:34:51.539831Z","end":"2026-01-26T15:34:52.034982Z","steps":["trace[1891632237] 'agreement among raft nodes before linearized reading' (duration: 495.10505ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:52.035008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.539817Z","time spent":"495.186142ms","remote":"127.0.0.1:39318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-26T15:34:52.141026Z","caller":"traceutil/trace.go:171","msg":"trace[679238040] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"312.013073ms","start":"2026-01-26T15:34:51.828994Z","end":"2026-01-26T15:34:52.141007Z","steps":["trace[679238040] 'process raft request' (duration: 311.933441ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:52.14136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.82897Z","time spent":"312.32598ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-26T15:34:52.141676Z","caller":"traceutil/trace.go:171","msg":"trace[1787451046] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"478.543908ms","start":"2026-01-26T15:34:51.663119Z","end":"2026-01-26T15:34:52.141663Z","steps":["trace[1787451046] 'process raft request' (duration: 477.639277ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:52.141782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.663104Z","time spent":"478.606539ms","remote":"127.0.0.1:39490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4613,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:34:52.141998Z","caller":"traceutil/trace.go:171","msg":"trace[2076695785] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"478.762963ms","start":"2026-01-26T15:34:51.663224Z","end":"2026-01-26T15:34:52.141986Z","steps":["trace[2076695785] 'process raft request' (duration: 477.626687ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:52.142073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.663198Z","time spent":"478.832984ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:34:52.142307Z","caller":"traceutil/trace.go:171","msg":"trace[60304528] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"478.977398ms","start":"2026-01-26T15:34:51.663217Z","end":"2026-01-26T15:34:52.142194Z","steps":["trace[60304528] 'process raft request' (duration: 477.673678ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:52.142391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:51.663211Z","time spent":"479.131752ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:34:52.143016Z","caller":"traceutil/trace.go:171","msg":"trace[37825251] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:527; }","duration":"108.193868ms","start":"2026-01-26T15:34:52.034811Z","end":"2026-01-26T15:34:52.143005Z","steps":["trace[37825251] 'read index received' (duration: 108.189358ms)","trace[37825251] 'applied index is now lower than readState.Index' (duration: 3.51µs)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:34:52.143103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.082774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-26T15:34:52.143137Z","caller":"traceutil/trace.go:171","msg":"trace[443435011] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:509; }","duration":"114.131755ms","start":"2026-01-26T15:34:52.028997Z","end":"2026-01-26T15:34:52.143128Z","steps":["trace[443435011] 'agreement among raft nodes before linearized reading' (duration: 114.069923ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:52.143283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.596274ms","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-01-26T15:34:52.14341Z","caller":"traceutil/trace.go:171","msg":"trace[752664456] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:509; }","duration":"120.749098ms","start":"2026-01-26T15:34:52.02265Z","end":"2026-01-26T15:34:52.143399Z","steps":["trace[752664456] 'agreement among raft nodes before linearized reading' (duration: 120.610235ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:52.146803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.707973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:52.146833Z","caller":"traceutil/trace.go:171","msg":"trace[1397520265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"106.762465ms","start":"2026-01-26T15:34:52.040064Z","end":"2026-01-26T15:34:52.146826Z","steps":["trace[1397520265] 'agreement among raft nodes before linearized reading' (duration: 106.699313ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:34:52.146987Z","caller":"traceutil/trace.go:171","msg":"trace[1133948191] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"100.914319ms","start":"2026-01-26T15:34:52.046066Z","end":"2026-01-26T15:34:52.146981Z","steps":["trace[1133948191] 'process raft request' (duration: 100.613312ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:34:52.147345Z","caller":"traceutil/trace.go:171","msg":"trace[1818362714] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"101.054723ms","start":"2026-01-26T15:34:52.046282Z","end":"2026-01-26T15:34:52.147337Z","steps":["trace[1818362714] 'process raft request' (duration: 100.456049ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:34:53.797835Z","caller":"traceutil/trace.go:171","msg":"trace[879301140] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"258.973019ms","start":"2026-01-26T15:34:53.538835Z","end":"2026-01-26T15:34:53.797808Z","steps":["trace[879301140] 'read index received' (duration: 251.901476ms)","trace[879301140] 'applied index is now lower than readState.Index' (duration: 7.069673ms)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:34:53.79796Z","caller":"traceutil/trace.go:171","msg":"trace[1121200706] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"268.035388ms","start":"2026-01-26T15:34:53.529915Z","end":"2026-01-26T15:34:53.797951Z","steps":["trace[1121200706] 'process raft request' (duration: 261.025507ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:53.798248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.39675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:53.798279Z","caller":"traceutil/trace.go:171","msg":"trace[926721818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"259.439361ms","start":"2026-01-26T15:34:53.53883Z","end":"2026-01-26T15:34:53.798269Z","steps":["trace[926721818] 'agreement among raft nodes before linearized reading' (duration: 259.356509ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:53.798489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.084377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-01-26T15:34:53.798518Z","caller":"traceutil/trace.go:171","msg":"trace[2125232893] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:526; }","duration":"170.147378ms","start":"2026-01-26T15:34:53.628361Z","end":"2026-01-26T15:34:53.798509Z","steps":["trace[2125232893] 'agreement among raft nodes before linearized reading' (duration: 170.045496ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:34:54.183986Z","caller":"traceutil/trace.go:171","msg":"trace[1715816607] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"277.429735ms","start":"2026-01-26T15:34:53.906538Z","end":"2026-01-26T15:34:54.183968Z","steps":["trace[1715816607] 'process raft request' (duration: 268.633202ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:34:54.18415Z","caller":"traceutil/trace.go:171","msg":"trace[1070223697] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"159.842851ms","start":"2026-01-26T15:34:54.024283Z","end":"2026-01-26T15:34:54.184126Z","steps":["trace[1070223697] 'read index received' (duration: 150.868454ms)","trace[1070223697] 'applied index is now lower than readState.Index' (duration: 8.973457ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:34:54.184729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.432874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-01-26T15:34:54.184763Z","caller":"traceutil/trace.go:171","msg":"trace[1127808194] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:528; }","duration":"160.498326ms","start":"2026-01-26T15:34:54.024254Z","end":"2026-01-26T15:34:54.184752Z","steps":["trace[1127808194] 'agreement among raft nodes before linearized reading' (duration: 160.434285ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:54.18493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.561284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-26T15:34:54.18495Z","caller":"traceutil/trace.go:171","msg":"trace[2079743978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"133.591985ms","start":"2026-01-26T15:34:54.051352Z","end":"2026-01-26T15:34:54.184944Z","steps":["trace[2079743978] 'agreement among raft nodes before linearized reading' (duration: 133.515693ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:58.706968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.364267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:58.707041Z","caller":"traceutil/trace.go:171","msg":"trace[2106192027] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:550; }","duration":"262.4762ms","start":"2026-01-26T15:34:58.444551Z","end":"2026-01-26T15:34:58.707027Z","steps":["trace[2106192027] 'range keys from in-memory index tree' (duration: 262.285295ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:58.70724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.059679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:58.707277Z","caller":"traceutil/trace.go:171","msg":"trace[795457586] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:550; }","duration":"265.11879ms","start":"2026-01-26T15:34:58.442151Z","end":"2026-01-26T15:34:58.70727Z","steps":["trace[795457586] 'range keys from in-memory index tree' (duration: 262.796507ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:58.707331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.832161ms","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":"warn","ts":"2026-01-26T15:34:58.707374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.763927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:58.707395Z","caller":"traceutil/trace.go:171","msg":"trace[499759701] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:550; }","duration":"262.794567ms","start":"2026-01-26T15:34:58.444594Z","end":"2026-01-26T15:34:58.707389Z","steps":["trace[499759701] 'count revisions from in-memory index tree' (duration: 262.741355ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:34:58.707404Z","caller":"traceutil/trace.go:171","msg":"trace[1231117899] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:550; }","duration":"250.927933ms","start":"2026-01-26T15:34:58.456456Z","end":"2026-01-26T15:34:58.707384Z","steps":["trace[1231117899] 'range keys from in-memory index tree' (duration: 250.80191ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:58.707632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.203969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:58.707671Z","caller":"traceutil/trace.go:171","msg":"trace[298989024] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:550; }","duration":"251.303161ms","start":"2026-01-26T15:34:58.456356Z","end":"2026-01-26T15:34:58.707659Z","steps":["trace[298989024] 'range keys from in-memory index tree' (duration: 251.088246ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:58.70767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.445391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:58.707708Z","caller":"traceutil/trace.go:171","msg":"trace[2091886793] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:550; }","duration":"224.485102ms","start":"2026-01-26T15:34:58.483216Z","end":"2026-01-26T15:34:58.707701Z","steps":["trace[2091886793] 'range keys from in-memory index tree' (duration: 224.40817ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:58.707842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.546934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:58.707882Z","caller":"traceutil/trace.go:171","msg":"trace[2062846172] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:550; }","duration":"224.598795ms","start":"2026-01-26T15:34:58.483271Z","end":"2026-01-26T15:34:58.70787Z","steps":["trace[2062846172] 'range keys from in-memory index tree' (duration: 224.527693ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:58.707938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.411814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:58.707997Z","caller":"traceutil/trace.go:171","msg":"trace[40374041] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:550; }","duration":"251.482416ms","start":"2026-01-26T15:34:58.456503Z","end":"2026-01-26T15:34:58.707986Z","steps":["trace[40374041] 'count revisions from in-memory index tree' (duration: 251.382393ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:58.708046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.739298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:58.708087Z","caller":"traceutil/trace.go:171","msg":"trace[83789310] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:550; }","duration":"224.784429ms","start":"2026-01-26T15:34:58.48329Z","end":"2026-01-26T15:34:58.708074Z","steps":["trace[83789310] 'count revisions from in-memory index tree' (duration: 224.655187ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:58.708172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.480096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:58.708205Z","caller":"traceutil/trace.go:171","msg":"trace[196155030] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:550; }","duration":"238.563008ms","start":"2026-01-26T15:34:58.469633Z","end":"2026-01-26T15:34:58.708196Z","steps":["trace[196155030] 'range keys from in-memory index tree' (duration: 238.312782ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:58.708259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.906687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:58.7083Z","caller":"traceutil/trace.go:171","msg":"trace[339601618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"167.964539ms","start":"2026-01-26T15:34:58.540323Z","end":"2026-01-26T15:34:58.708288Z","steps":["trace[339601618] 'range keys from in-memory index tree' (duration: 167.853296ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:58.708404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.322149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:58.708434Z","caller":"traceutil/trace.go:171","msg":"trace[2020602683] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:550; }","duration":"237.394321ms","start":"2026-01-26T15:34:58.471031Z","end":"2026-01-26T15:34:58.708426Z","steps":["trace[2020602683] 'range keys from in-memory index tree' (duration: 236.647354ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:58.708594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.485813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:34:58.708621Z","caller":"traceutil/trace.go:171","msg":"trace[158511241] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:550; }","duration":"237.527383ms","start":"2026-01-26T15:34:58.471086Z","end":"2026-01-26T15:34:58.708613Z","steps":["trace[158511241] 'count revisions from in-memory index tree' (duration: 235.393955ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:34:59.486092Z","caller":"traceutil/trace.go:171","msg":"trace[1918061548] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"760.079706ms","start":"2026-01-26T15:34:58.725989Z","end":"2026-01-26T15:34:59.486069Z","steps":["trace[1918061548] 'process raft request' (duration: 759.892012ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:34:59.486226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:58.725976Z","time spent":"760.179299ms","remote":"127.0.0.1:39490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:35:00.27102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.322741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:35:00.271062Z","caller":"traceutil/trace.go:171","msg":"trace[1856255664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"730.408043ms","start":"2026-01-26T15:34:59.540645Z","end":"2026-01-26T15:35:00.271053Z","steps":["trace[1856255664] 'range keys from in-memory index tree' (duration: 730.252869ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:00.271083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:34:59.540625Z","time spent":"730.452414ms","remote":"127.0.0.1:39318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-26T15:35:00.458898Z","caller":"traceutil/trace.go:171","msg":"trace[1936658561] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"183.447755ms","start":"2026-01-26T15:35:00.275435Z","end":"2026-01-26T15:35:00.458882Z","steps":["trace[1936658561] 'process raft request' (duration: 183.364023ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:00.463396Z","caller":"traceutil/trace.go:171","msg":"trace[505218940] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:571; }","duration":"185.657877ms","start":"2026-01-26T15:35:00.277728Z","end":"2026-01-26T15:35:00.463386Z","steps":["trace[505218940] 'read index received' (duration: 181.121232ms)","trace[505218940] 'applied index is now lower than readState.Index' (duration: 4.535975ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:35:00.463536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.79663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-26T15:35:00.463589Z","caller":"traceutil/trace.go:171","msg":"trace[368918841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"185.869341ms","start":"2026-01-26T15:35:00.277713Z","end":"2026-01-26T15:35:00.463582Z","steps":["trace[368918841] 'agreement among raft nodes before linearized reading' (duration: 185.710958ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:00.463826Z","caller":"traceutil/trace.go:171","msg":"trace[1770668103] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"185.307148ms","start":"2026-01-26T15:35:00.278493Z","end":"2026-01-26T15:35:00.4638Z","steps":["trace[1770668103] 'process raft request' (duration: 184.849128ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:00.463915Z","caller":"traceutil/trace.go:171","msg":"trace[309494244] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"187.836507ms","start":"2026-01-26T15:35:00.276072Z","end":"2026-01-26T15:35:00.463909Z","steps":["trace[309494244] 'process raft request' (duration: 187.214773ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:00.464009Z","caller":"traceutil/trace.go:171","msg":"trace[217994333] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"185.430811ms","start":"2026-01-26T15:35:00.278557Z","end":"2026-01-26T15:35:00.463987Z","steps":["trace[217994333] 'process raft request' (duration: 184.810186ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:08.249936Z","caller":"traceutil/trace.go:171","msg":"trace[2040029887] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"138.562069ms","start":"2026-01-26T15:35:08.111357Z","end":"2026-01-26T15:35:08.249919Z","steps":["trace[2040029887] 'process raft request' (duration: 138.434066ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:08.256101Z","caller":"traceutil/trace.go:171","msg":"trace[1390756188] transaction","detail":"{read_only:false; number_of_response:0; response_revision:603; }","duration":"143.333469ms","start":"2026-01-26T15:35:08.112754Z","end":"2026-01-26T15:35:08.256088Z","steps":["trace[1390756188] 'process raft request' (duration: 143.116264ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:18.851781Z","caller":"traceutil/trace.go:171","msg":"trace[977792054] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:718; }","duration":"301.506321ms","start":"2026-01-26T15:35:18.550259Z","end":"2026-01-26T15:35:18.851765Z","steps":["trace[977792054] 'read index received' (duration: 301.373238ms)","trace[977792054] 'applied index is now lower than readState.Index' (duration: 132.263µs)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:35:18.851958Z","caller":"traceutil/trace.go:171","msg":"trace[1677398520] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"324.975463ms","start":"2026-01-26T15:35:18.526973Z","end":"2026-01-26T15:35:18.851949Z","steps":["trace[1677398520] 'process raft request' (duration: 324.699677ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:18.852026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:18.526963Z","time spent":"325.013664ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:35:18.852186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.92416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-26T15:35:18.852206Z","caller":"traceutil/trace.go:171","msg":"trace[338113577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:693; }","duration":"301.957092ms","start":"2026-01-26T15:35:18.550243Z","end":"2026-01-26T15:35:18.8522Z","steps":["trace[338113577] 'agreement among raft nodes before linearized reading' (duration: 301.88487ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:18.852239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:18.550236Z","time spent":"301.999133ms","remote":"127.0.0.1:40362","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-01-26T15:35:18.852362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.827628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-26T15:35:18.852379Z","caller":"traceutil/trace.go:171","msg":"trace[845482961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"301.844279ms","start":"2026-01-26T15:35:18.55053Z","end":"2026-01-26T15:35:18.852374Z","steps":["trace[845482961] 'agreement among raft nodes before linearized reading' (duration: 301.794918ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:18.852394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:18.550525Z","time spent":"301.866229ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-26T15:35:18.852511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.683059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:35:18.85253Z","caller":"traceutil/trace.go:171","msg":"trace[733204071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"163.73026ms","start":"2026-01-26T15:35:18.688795Z","end":"2026-01-26T15:35:18.852525Z","steps":["trace[733204071] 'agreement among raft nodes before linearized reading' (duration: 163.70356ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:18.852645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.927249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-26T15:35:18.852661Z","caller":"traceutil/trace.go:171","msg":"trace[399950833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:693; }","duration":"281.997931ms","start":"2026-01-26T15:35:18.570658Z","end":"2026-01-26T15:35:18.852656Z","steps":["trace[399950833] 'agreement among raft nodes before linearized reading' (duration: 281.95338ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:18.852767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.027044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-26T15:35:18.852782Z","caller":"traceutil/trace.go:171","msg":"trace[2059505375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"286.054654ms","start":"2026-01-26T15:35:18.566723Z","end":"2026-01-26T15:35:18.852777Z","steps":["trace[2059505375] 'agreement among raft nodes before linearized reading' (duration: 286.001383ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:18.978377Z","caller":"traceutil/trace.go:171","msg":"trace[523578803] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"117.692007ms","start":"2026-01-26T15:35:18.860554Z","end":"2026-01-26T15:35:18.978246Z","steps":["trace[523578803] 'process raft request' (duration: 110.579093ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:18.978521Z","caller":"traceutil/trace.go:171","msg":"trace[1726778174] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"113.808258ms","start":"2026-01-26T15:35:18.864692Z","end":"2026-01-26T15:35:18.9785Z","steps":["trace[1726778174] 'process raft request' (duration: 113.507601ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:18.978769Z","caller":"traceutil/trace.go:171","msg":"trace[2087049138] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"114.021373ms","start":"2026-01-26T15:35:18.86474Z","end":"2026-01-26T15:35:18.978761Z","steps":["trace[2087049138] 'process raft request' (duration: 113.687215ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:21.442747Z","caller":"traceutil/trace.go:171","msg":"trace[609848303] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"553.18924ms","start":"2026-01-26T15:35:20.889533Z","end":"2026-01-26T15:35:21.442722Z","steps":["trace[609848303] 'process raft request' (duration: 528.344728ms)","trace[609848303] 'compare' (duration: 24.553575ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:35:21.442882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:20.889522Z","time spent":"553.285812ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:35:21.786725Z","caller":"traceutil/trace.go:171","msg":"trace[1732432718] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:724; }","duration":"804.41858ms","start":"2026-01-26T15:35:20.98229Z","end":"2026-01-26T15:35:21.786709Z","steps":["trace[1732432718] 'read index received' (duration: 435.595512ms)","trace[1732432718] 'applied index is now lower than readState.Index' (duration: 368.822318ms)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:35:21.786794Z","caller":"traceutil/trace.go:171","msg":"trace[429565294] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"845.984811ms","start":"2026-01-26T15:35:20.940803Z","end":"2026-01-26T15:35:21.786788Z","steps":["trace[429565294] 'process raft request' (duration: 840.738846ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:21.786874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:20.940779Z","time spent":"846.037913ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-26T15:35:21.787074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.780969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-26T15:35:21.787094Z","caller":"traceutil/trace.go:171","msg":"trace[1251720229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:700; }","duration":"804.838599ms","start":"2026-01-26T15:35:20.98225Z","end":"2026-01-26T15:35:21.787088Z","steps":["trace[1251720229] 'agreement among raft nodes before linearized reading' (duration: 804.736737ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:21.787111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:20.982215Z","time spent":"804.892671ms","remote":"127.0.0.1:40362","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-01-26T15:35:21.787228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.089709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-26T15:35:21.787246Z","caller":"traceutil/trace.go:171","msg":"trace[1969833673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:700; }","duration":"803.126749ms","start":"2026-01-26T15:35:20.984113Z","end":"2026-01-26T15:35:21.78724Z","steps":["trace[1969833673] 'agreement among raft nodes before linearized reading' (duration: 803.079738ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:21.787261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:20.984102Z","time spent":"803.155339ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-26T15:35:21.787415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.828348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:35:21.787438Z","caller":"traceutil/trace.go:171","msg":"trace[1988544470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"246.88278ms","start":"2026-01-26T15:35:21.54055Z","end":"2026-01-26T15:35:21.787433Z","steps":["trace[1988544470] 'agreement among raft nodes before linearized reading' (duration: 246.847049ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:21.787561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.202124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-26T15:35:21.787577Z","caller":"traceutil/trace.go:171","msg":"trace[1353623098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:700; }","duration":"801.237634ms","start":"2026-01-26T15:35:20.986334Z","end":"2026-01-26T15:35:21.787572Z","steps":["trace[1353623098] 'agreement among raft nodes before linearized reading' (duration: 801.177263ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:21.787592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:20.986324Z","time spent":"801.264644ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-26T15:35:34.016694Z","caller":"traceutil/trace.go:171","msg":"trace[1058190769] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"109.348908ms","start":"2026-01-26T15:35:33.90732Z","end":"2026-01-26T15:35:34.016668Z","steps":["trace[1058190769] 'read index received' (duration: 109.227425ms)","trace[1058190769] 'applied index is now lower than readState.Index' (duration: 120.433µs)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:35:34.016817Z","caller":"traceutil/trace.go:171","msg":"trace[2120496992] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"145.088151ms","start":"2026-01-26T15:35:33.871719Z","end":"2026-01-26T15:35:34.016807Z","steps":["trace[2120496992] 'process raft request' (duration: 144.836665ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:34.017021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.687086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-26T15:35:34.01705Z","caller":"traceutil/trace.go:171","msg":"trace[1854353992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"109.753978ms","start":"2026-01-26T15:35:33.907287Z","end":"2026-01-26T15:35:34.017041Z","steps":["trace[1854353992] 'agreement among raft nodes before linearized reading' (duration: 109.626855ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:35.05642Z","caller":"traceutil/trace.go:171","msg":"trace[1740560203] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"146.638518ms","start":"2026-01-26T15:35:34.909759Z","end":"2026-01-26T15:35:35.056398Z","steps":["trace[1740560203] 'process raft request' (duration: 146.519565ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:39.187138Z","caller":"traceutil/trace.go:171","msg":"trace[683670236] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"116.98336ms","start":"2026-01-26T15:35:39.070139Z","end":"2026-01-26T15:35:39.187123Z","steps":["trace[683670236] 'process raft request' (duration: 116.900338ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:40.082711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:39.354063Z","time spent":"728.643892ms","remote":"127.0.0.1:48756","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-26T15:35:40.082987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.928604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-26T15:35:40.08302Z","caller":"traceutil/trace.go:171","msg":"trace[199317525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"579.975905ms","start":"2026-01-26T15:35:39.503034Z","end":"2026-01-26T15:35:40.083009Z","steps":["trace[199317525] 'range keys from in-memory index tree' (duration: 579.840552ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:40.083041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:39.502995Z","time spent":"580.042077ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-26T15:35:40.083183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.870488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-26T15:35:40.083212Z","caller":"traceutil/trace.go:171","msg":"trace[1988424342] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:756; }","duration":"570.900879ms","start":"2026-01-26T15:35:39.512303Z","end":"2026-01-26T15:35:40.083204Z","steps":["trace[1988424342] 'count revisions from in-memory index tree' (duration: 570.840807ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:40.083233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:39.512296Z","time spent":"570.931219ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":80,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-01-26T15:35:40.08333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.156341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:35:40.08335Z","caller":"traceutil/trace.go:171","msg":"trace[2066710177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"544.202712ms","start":"2026-01-26T15:35:39.539143Z","end":"2026-01-26T15:35:40.083346Z","steps":["trace[2066710177] 'range keys from in-memory index tree' (duration: 544.112269ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:40.083367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:39.539131Z","time spent":"544.232402ms","remote":"127.0.0.1:39330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-26T15:35:40.083448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.050124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-26T15:35:40.083468Z","caller":"traceutil/trace.go:171","msg":"trace[939242520] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:756; }","duration":"159.099765ms","start":"2026-01-26T15:35:39.924364Z","end":"2026-01-26T15:35:40.083464Z","steps":["trace[939242520] 'count revisions from in-memory index tree' (duration: 159.009743ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:43.360955Z","caller":"traceutil/trace.go:171","msg":"trace[1926952566] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"161.060361ms","start":"2026-01-26T15:35:43.199874Z","end":"2026-01-26T15:35:43.360934Z","steps":["trace[1926952566] 'process raft request' (duration: 160.965389ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:44.92953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.916311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-26T15:35:44.929789Z","caller":"traceutil/trace.go:171","msg":"trace[598220230] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"727.037093ms","start":"2026-01-26T15:35:44.20274Z","end":"2026-01-26T15:35:44.929777Z","steps":["trace[598220230] 'read index received' (duration: 331.645421ms)","trace[598220230] 'applied index is now lower than readState.Index' (duration: 395.390152ms)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:35:44.930018Z","caller":"traceutil/trace.go:171","msg":"trace[1594387035] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"727.827372ms","start":"2026-01-26T15:35:44.202182Z","end":"2026-01-26T15:35:44.930009Z","steps":["trace[1594387035] 'process raft request' (duration: 332.252106ms)","trace[1594387035] 'compare' (duration: 393.927277ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:35:44.930058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:44.202155Z","time spent":"727.882783ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:35:44.930237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.493944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-26T15:35:44.930255Z","caller":"traceutil/trace.go:171","msg":"trace[1285958099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:771; }","duration":"727.532555ms","start":"2026-01-26T15:35:44.202717Z","end":"2026-01-26T15:35:44.930249Z","steps":["trace[1285958099] 'agreement among raft nodes before linearized reading' (duration: 727.444243ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:44.930273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:44.202707Z","time spent":"727.561936ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-26T15:35:44.930404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.009207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:35:44.930467Z","caller":"traceutil/trace.go:171","msg":"trace[115117062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"391.09649ms","start":"2026-01-26T15:35:44.539352Z","end":"2026-01-26T15:35:44.930448Z","steps":["trace[115117062] 'agreement among raft nodes before linearized reading' (duration: 391.000098ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:44.930495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.515023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-01-26T15:35:44.930502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:44.539342Z","time spent":"391.153161ms","remote":"127.0.0.1:39318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-26T15:35:44.930518Z","caller":"traceutil/trace.go:171","msg":"trace[818422431] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:771; }","duration":"226.568654ms","start":"2026-01-26T15:35:44.703944Z","end":"2026-01-26T15:35:44.930513Z","steps":["trace[818422431] 'agreement among raft nodes before linearized reading' (duration: 226.528953ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:45.64956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.969777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-26T15:35:45.649667Z","caller":"traceutil/trace.go:171","msg":"trace[1400816892] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"284.381363ms","start":"2026-01-26T15:35:45.365271Z","end":"2026-01-26T15:35:45.649653Z","steps":["trace[1400816892] 'read index received' (duration: 31.491µs)","trace[1400816892] 'applied index is now lower than readState.Index' (duration: 284.348351ms)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:35:45.6507Z","caller":"traceutil/trace.go:171","msg":"trace[175467904] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"713.650692ms","start":"2026-01-26T15:35:44.936181Z","end":"2026-01-26T15:35:45.649831Z","steps":["trace[175467904] 'process raft request' (duration: 127.346607ms)","trace[175467904] 'compare' (duration: 585.098286ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:35:45.650777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:44.936161Z","time spent":"714.577364ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:35:45.65101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.735395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-26T15:35:45.651041Z","caller":"traceutil/trace.go:171","msg":"trace[1829938070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:772; }","duration":"285.797646ms","start":"2026-01-26T15:35:45.365231Z","end":"2026-01-26T15:35:45.651029Z","steps":["trace[1829938070] 'agreement among raft nodes before linearized reading' (duration: 285.694654ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:45.672042Z","caller":"traceutil/trace.go:171","msg":"trace[1161428702] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"168.124939ms","start":"2026-01-26T15:35:45.503907Z","end":"2026-01-26T15:35:45.672032Z","steps":["trace[1161428702] 'process raft request' (duration: 168.067808ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:45.67229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.480955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:35:45.672308Z","caller":"traceutil/trace.go:171","msg":"trace[606537453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"131.541527ms","start":"2026-01-26T15:35:45.540761Z","end":"2026-01-26T15:35:45.672302Z","steps":["trace[606537453] 'agreement among raft nodes before linearized reading' (duration: 131.501306ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:35:45.998001Z","caller":"traceutil/trace.go:171","msg":"trace[307880469] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"332.88513ms","start":"2026-01-26T15:35:45.665099Z","end":"2026-01-26T15:35:45.997984Z","steps":["trace[307880469] 'process raft request' (duration: 332.243945ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:35:45.998087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:35:45.665087Z","time spent":"332.953823ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:35:59.246537Z","caller":"traceutil/trace.go:171","msg":"trace[225704444] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"232.450485ms","start":"2026-01-26T15:35:59.01406Z","end":"2026-01-26T15:35:59.24651Z","steps":["trace[225704444] 'process raft request' (duration: 232.307812ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:36:01.536115Z","caller":"traceutil/trace.go:171","msg":"trace[1197686141] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"283.116834ms","start":"2026-01-26T15:36:01.252983Z","end":"2026-01-26T15:36:01.5361Z","steps":["trace[1197686141] 'process raft request' (duration: 283.012261ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:36:02.244542Z","caller":"traceutil/trace.go:171","msg":"trace[867736929] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"185.531946ms","start":"2026-01-26T15:36:02.058999Z","end":"2026-01-26T15:36:02.244531Z","steps":["trace[867736929] 'read index received' (duration: 185.460254ms)","trace[867736929] 'applied index is now lower than readState.Index' (duration: 70.422µs)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:36:02.244676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.669259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-26T15:36:02.244693Z","caller":"traceutil/trace.go:171","msg":"trace[627585246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:874; }","duration":"185.704969ms","start":"2026-01-26T15:36:02.058982Z","end":"2026-01-26T15:36:02.244687Z","steps":["trace[627585246] 'agreement among raft nodes before linearized reading' (duration: 185.622507ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:02.244817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.05089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-26T15:36:02.244864Z","caller":"traceutil/trace.go:171","msg":"trace[543680661] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:874; }","duration":"163.113461ms","start":"2026-01-26T15:36:02.081738Z","end":"2026-01-26T15:36:02.244852Z","steps":["trace[543680661] 'agreement among raft nodes before linearized reading' (duration: 163.026349ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:03.572488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.394226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-26T15:36:03.572558Z","caller":"traceutil/trace.go:171","msg":"trace[581403950] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:879; }","duration":"199.531259ms","start":"2026-01-26T15:36:03.373011Z","end":"2026-01-26T15:36:03.572542Z","steps":["trace[581403950] 'count revisions from in-memory index tree' (duration: 199.331213ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:36:03.878791Z","caller":"traceutil/trace.go:171","msg":"trace[1912228875] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"206.544557ms","start":"2026-01-26T15:36:03.672227Z","end":"2026-01-26T15:36:03.878771Z","steps":["trace[1912228875] 'read index received' (duration: 206.428564ms)","trace[1912228875] 'applied index is now lower than readState.Index' (duration: 115.283µs)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:36:03.878978Z","caller":"traceutil/trace.go:171","msg":"trace[220262060] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"297.206289ms","start":"2026-01-26T15:36:03.581765Z","end":"2026-01-26T15:36:03.878971Z","steps":["trace[220262060] 'process raft request' (duration: 296.930603ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:03.879147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.903025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:03.879178Z","caller":"traceutil/trace.go:171","msg":"trace[1307922808] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:880; }","duration":"206.970247ms","start":"2026-01-26T15:36:03.6722Z","end":"2026-01-26T15:36:03.87917Z","steps":["trace[1307922808] 'agreement among raft nodes before linearized reading' (duration: 206.910156ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:03.87992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.106152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-26T15:36:03.879957Z","caller":"traceutil/trace.go:171","msg":"trace[165570584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"135.183605ms","start":"2026-01-26T15:36:03.744766Z","end":"2026-01-26T15:36:03.879949Z","steps":["trace[165570584] 'agreement among raft nodes before linearized reading' (duration: 134.502748ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:36:04.831885Z","caller":"traceutil/trace.go:171","msg":"trace[264256865] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:919; }","duration":"483.670157ms","start":"2026-01-26T15:36:04.348201Z","end":"2026-01-26T15:36:04.831871Z","steps":["trace[264256865] 'read index received' (duration: 483.503973ms)","trace[264256865] 'applied index is now lower than readState.Index' (duration: 165.424µs)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:36:04.832073Z","caller":"traceutil/trace.go:171","msg":"trace[328054838] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"506.306538ms","start":"2026-01-26T15:36:04.32576Z","end":"2026-01-26T15:36:04.832067Z","steps":["trace[328054838] 'process raft request' (duration: 505.99273ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:04.832134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:04.325746Z","time spent":"506.341778ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:36:04.832224Z","caller":"traceutil/trace.go:171","msg":"trace[233152723] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"504.926894ms","start":"2026-01-26T15:36:04.327292Z","end":"2026-01-26T15:36:04.832219Z","steps":["trace[233152723] 'process raft request' (duration: 504.556796ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:04.832246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:04.327284Z","time spent":"504.949376ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:36:04.832344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.151359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-26T15:36:04.83236Z","caller":"traceutil/trace.go:171","msg":"trace[967292056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"484.19043ms","start":"2026-01-26T15:36:04.348163Z","end":"2026-01-26T15:36:04.832354Z","steps":["trace[967292056] 'agreement among raft nodes before linearized reading' (duration: 484.131838ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:04.832373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:04.348115Z","time spent":"484.254481ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-26T15:36:04.832431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.469557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:04.832442Z","caller":"traceutil/trace.go:171","msg":"trace[1780070653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"235.505508ms","start":"2026-01-26T15:36:04.596933Z","end":"2026-01-26T15:36:04.832439Z","steps":["trace[1780070653] 'agreement among raft nodes before linearized reading' (duration: 235.486747ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:07.5393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.259444ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:07.539432Z","caller":"traceutil/trace.go:171","msg":"trace[330253119] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:890; }","duration":"617.397057ms","start":"2026-01-26T15:36:06.922014Z","end":"2026-01-26T15:36:07.539411Z","steps":["trace[330253119] 'range keys from in-memory index tree' (duration: 617.246203ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:36:07.930122Z","caller":"traceutil/trace.go:171","msg":"trace[1549534505] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"381.748656ms","start":"2026-01-26T15:36:07.548356Z","end":"2026-01-26T15:36:07.930105Z","steps":["trace[1549534505] 'read index received' (duration: 283.937493ms)","trace[1549534505] 'applied index is now lower than readState.Index' (duration: 97.809683ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:36:07.930265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.90075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:07.930304Z","caller":"traceutil/trace.go:171","msg":"trace[701178323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"381.949251ms","start":"2026-01-26T15:36:07.548347Z","end":"2026-01-26T15:36:07.930296Z","steps":["trace[701178323] 'agreement among raft nodes before linearized reading' (duration: 381.880469ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:07.930329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:07.548314Z","time spent":"382.010502ms","remote":"127.0.0.1:39318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-26T15:36:13.927832Z","caller":"traceutil/trace.go:171","msg":"trace[16626870] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"389.475111ms","start":"2026-01-26T15:36:13.53834Z","end":"2026-01-26T15:36:13.927815Z","steps":["trace[16626870] 'read index received' (duration: 389.350358ms)","trace[16626870] 'applied index is now lower than readState.Index' (duration: 123.933µs)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:36:13.927932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.574783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:13.927948Z","caller":"traceutil/trace.go:171","msg":"trace[1651910357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"389.623134ms","start":"2026-01-26T15:36:13.53832Z","end":"2026-01-26T15:36:13.927943Z","steps":["trace[1651910357] 'agreement among raft nodes before linearized reading' (duration: 389.556332ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:13.927966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:13.538314Z","time spent":"389.647935ms","remote":"127.0.0.1:39318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-26T15:36:13.92815Z","caller":"traceutil/trace.go:171","msg":"trace[1053731679] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"390.739941ms","start":"2026-01-26T15:36:13.537384Z","end":"2026-01-26T15:36:13.928124Z","steps":["trace[1053731679] 'process raft request' (duration: 390.321951ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:13.928253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:13.537368Z","time spent":"390.832543ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:36:15.222459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.620694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-26T15:36:15.222529Z","caller":"traceutil/trace.go:171","msg":"trace[176821011] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"264.221222ms","start":"2026-01-26T15:36:14.958298Z","end":"2026-01-26T15:36:15.222519Z","steps":["trace[176821011] 'process raft request' (duration: 35.487056ms)","trace[176821011] 'compare' (duration: 228.377398ms)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:36:16.119678Z","caller":"traceutil/trace.go:171","msg":"trace[121378473] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"125.489674ms","start":"2026-01-26T15:36:15.994167Z","end":"2026-01-26T15:36:16.119657Z","steps":["trace[121378473] 'read index received' (duration: 125.32926ms)","trace[121378473] 'applied index is now lower than readState.Index' (duration: 159.534µs)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:36:16.119889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.700279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-26T15:36:16.119925Z","caller":"traceutil/trace.go:171","msg":"trace[1595349372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:913; }","duration":"125.77292ms","start":"2026-01-26T15:36:15.994143Z","end":"2026-01-26T15:36:16.119916Z","steps":["trace[1595349372] 'agreement among raft nodes before linearized reading' (duration: 125.601346ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:36:16.120207Z","caller":"traceutil/trace.go:171","msg":"trace[1603279359] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"145.771438ms","start":"2026-01-26T15:36:15.974423Z","end":"2026-01-26T15:36:16.120194Z","steps":["trace[1603279359] 'process raft request' (duration: 145.129132ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:16.686437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408002349486343,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-26T15:36:17.167033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.321287ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:17.167104Z","caller":"traceutil/trace.go:171","msg":"trace[184281304] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:913; }","duration":"244.399349ms","start":"2026-01-26T15:36:16.922696Z","end":"2026-01-26T15:36:17.167095Z","steps":["trace[184281304] 'range keys from in-memory index tree' (duration: 244.312167ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:17.167425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.632832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-26T15:36:17.167464Z","caller":"traceutil/trace.go:171","msg":"trace[1473457436] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"1.044182018s","start":"2026-01-26T15:36:16.123276Z","end":"2026-01-26T15:36:17.167458Z","steps":["trace[1473457436] 'process raft request' (duration: 595.477555ms)","trace[1473457436] 'compare' (duration: 448.086648ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:36:17.16749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:16.123258Z","time spent":"1.044218388s","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:36:17.246806Z","caller":"traceutil/trace.go:171","msg":"trace[763242804] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:953; }","duration":"1.060659671s","start":"2026-01-26T15:36:16.186129Z","end":"2026-01-26T15:36:17.246789Z","steps":["trace[763242804] 'read index received' (duration: 532.630436ms)","trace[763242804] 'applied index is now lower than readState.Index' (duration: 528.028405ms)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:36:17.246877Z","caller":"traceutil/trace.go:171","msg":"trace[1519578088] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"997.277419ms","start":"2026-01-26T15:36:16.249595Z","end":"2026-01-26T15:36:17.246872Z","steps":["trace[1519578088] 'process raft request' (duration: 997.106665ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:17.246946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:16.249578Z","time spent":"997.3138ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:36:17.247058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024281143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:17.247114Z","caller":"traceutil/trace.go:171","msg":"trace[607862829] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:915; }","duration":"1.024398136s","start":"2026-01-26T15:36:16.222705Z","end":"2026-01-26T15:36:17.247103Z","steps":["trace[607862829] 'agreement among raft nodes before linearized reading' (duration: 1.024310844s)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:17.24714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:16.222692Z","time spent":"1.024441366s","remote":"127.0.0.1:39462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-01-26T15:36:17.247216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.419056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-26T15:36:17.24724Z","caller":"traceutil/trace.go:171","msg":"trace[374778271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"287.471637ms","start":"2026-01-26T15:36:16.959762Z","end":"2026-01-26T15:36:17.247233Z","steps":["trace[374778271] 'agreement among raft nodes before linearized reading' (duration: 287.391315ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:17.247341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061216244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:17.247355Z","caller":"traceutil/trace.go:171","msg":"trace[1826239989] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:915; }","duration":"1.061267026s","start":"2026-01-26T15:36:16.186084Z","end":"2026-01-26T15:36:17.247351Z","steps":["trace[1826239989] 'agreement among raft nodes before linearized reading' (duration: 1.061240145s)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:17.24737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:16.186063Z","time spent":"1.061302446s","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-01-26T15:36:17.247402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.951226ms","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-01-26T15:36:17.247427Z","caller":"traceutil/trace.go:171","msg":"trace[515640602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:915; }","duration":"339.010506ms","start":"2026-01-26T15:36:16.908409Z","end":"2026-01-26T15:36:17.24742Z","steps":["trace[515640602] 'agreement among raft nodes before linearized reading' (duration: 338.928504ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:17.247457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:16.908392Z","time spent":"339.055828ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-26T15:36:17.247587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.204516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:17.247605Z","caller":"traceutil/trace.go:171","msg":"trace[1038867722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"706.250017ms","start":"2026-01-26T15:36:16.541351Z","end":"2026-01-26T15:36:17.247601Z","steps":["trace[1038867722] 'agreement among raft nodes before linearized reading' (duration: 706.224916ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:17.247617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:16.541335Z","time spent":"706.279848ms","remote":"127.0.0.1:39318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-26T15:36:17.817057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.029531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-26T15:36:17.817117Z","caller":"traceutil/trace.go:171","msg":"trace[562769833] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"563.611974ms","start":"2026-01-26T15:36:17.253494Z","end":"2026-01-26T15:36:17.817106Z","steps":["trace[562769833] 'process raft request' (duration: 207.486ms)","trace[562769833] 'compare' (duration: 355.857477ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:36:17.817166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:17.25348Z","time spent":"563.671115ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:36:17.84317Z","caller":"traceutil/trace.go:171","msg":"trace[901234742] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"589.561182ms","start":"2026-01-26T15:36:17.253596Z","end":"2026-01-26T15:36:17.843157Z","steps":["trace[901234742] 'process raft request' (duration: 589.287006ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:17.843233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:17.253588Z","time spent":"589.614214ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:36:17.843388Z","caller":"traceutil/trace.go:171","msg":"trace[845212542] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"587.219527ms","start":"2026-01-26T15:36:17.256161Z","end":"2026-01-26T15:36:17.843381Z","steps":["trace[845212542] 'process raft request' (duration: 586.865268ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:17.843423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:17.256152Z","time spent":"587.253098ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:36:18.466831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.708818ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-26T15:36:18.466896Z","caller":"traceutil/trace.go:171","msg":"trace[1828532250] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:956; }","duration":"649.32965ms","start":"2026-01-26T15:36:17.817554Z","end":"2026-01-26T15:36:18.466884Z","steps":["trace[1828532250] 'read index received' (duration: 25.263673ms)","trace[1828532250] 'applied index is now lower than readState.Index' (duration: 624.064357ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:36:18.46749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.927534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:18.467513Z","caller":"traceutil/trace.go:171","msg":"trace[751791072] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:918; }","duration":"649.984164ms","start":"2026-01-26T15:36:17.817522Z","end":"2026-01-26T15:36:18.467506Z","steps":["trace[751791072] 'agreement among raft nodes before linearized reading' (duration: 649.935213ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:18.467535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:17.817512Z","time spent":"650.017196ms","remote":"127.0.0.1:40620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-01-26T15:36:18.467796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.413652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-26T15:36:18.46787Z","caller":"traceutil/trace.go:171","msg":"trace[138524610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:918; }","duration":"343.536825ms","start":"2026-01-26T15:36:18.124315Z","end":"2026-01-26T15:36:18.467852Z","steps":["trace[138524610] 'agreement among raft nodes before linearized reading' (duration: 343.293789ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:18.46795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:18.124299Z","time spent":"343.640317ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-26T15:36:20.031761Z","caller":"traceutil/trace.go:171","msg":"trace[1724194909] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"206.339122ms","start":"2026-01-26T15:36:19.825391Z","end":"2026-01-26T15:36:20.03173Z","steps":["trace[1724194909] 'process raft request' (duration: 206.171948ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:36:20.032353Z","caller":"traceutil/trace.go:171","msg":"trace[73083539] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:962; }","duration":"185.311101ms","start":"2026-01-26T15:36:19.847029Z","end":"2026-01-26T15:36:20.03234Z","steps":["trace[73083539] 'read index received' (duration: 185.30531ms)","trace[73083539] 'applied index is now lower than readState.Index' (duration: 4.57µs)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:36:20.03258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.532036ms","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-01-26T15:36:20.032622Z","caller":"traceutil/trace.go:171","msg":"trace[192174811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:921; }","duration":"185.584467ms","start":"2026-01-26T15:36:19.847024Z","end":"2026-01-26T15:36:20.032609Z","steps":["trace[192174811] 'agreement among raft nodes before linearized reading' (duration: 185.441864ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:20.03329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.896591ms","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-01-26T15:36:20.033325Z","caller":"traceutil/trace.go:171","msg":"trace[587121794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"184.955532ms","start":"2026-01-26T15:36:19.84836Z","end":"2026-01-26T15:36:20.033315Z","steps":["trace[587121794] 'agreement among raft nodes before linearized reading' (duration: 184.565742ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:20.549108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.935903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-26T15:36:20.550213Z","caller":"traceutil/trace.go:171","msg":"trace[1224417305] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"506.41593ms","start":"2026-01-26T15:36:20.043782Z","end":"2026-01-26T15:36:20.550198Z","steps":["trace[1224417305] 'process raft request' (duration: 260.32707ms)","trace[1224417305] 'compare' (duration: 244.670356ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:36:20.550312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:20.043774Z","time spent":"506.508552ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:36:20.796625Z","caller":"traceutil/trace.go:171","msg":"trace[824470125] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"168.475899ms","start":"2026-01-26T15:36:20.628098Z","end":"2026-01-26T15:36:20.796574Z","steps":["trace[824470125] 'read index received' (duration: 107.74781ms)","trace[824470125] 'applied index is now lower than readState.Index' (duration: 60.726659ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:36:20.796754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.624572ms","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-01-26T15:36:20.796788Z","caller":"traceutil/trace.go:171","msg":"trace[300264187] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:924; }","duration":"168.716125ms","start":"2026-01-26T15:36:20.628061Z","end":"2026-01-26T15:36:20.796778Z","steps":["trace[300264187] 'agreement among raft nodes before linearized reading' (duration: 168.627573ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:36:20.796834Z","caller":"traceutil/trace.go:171","msg":"trace[1350491673] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"241.227565ms","start":"2026-01-26T15:36:20.555558Z","end":"2026-01-26T15:36:20.796785Z","steps":["trace[1350491673] 'process raft request' (duration: 180.329202ms)","trace[1350491673] 'compare' (duration: 60.541944ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:36:22.743883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408002349486383,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-26T15:36:22.847599Z","caller":"traceutil/trace.go:171","msg":"trace[1589950457] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"604.156651ms","start":"2026-01-26T15:36:22.243427Z","end":"2026-01-26T15:36:22.847584Z","steps":["trace[1589950457] 'read index received' (duration: 604.021188ms)","trace[1589950457] 'applied index is now lower than readState.Index' (duration: 134.923µs)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:36:22.84767Z","caller":"traceutil/trace.go:171","msg":"trace[1635451846] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"681.015125ms","start":"2026-01-26T15:36:22.16665Z","end":"2026-01-26T15:36:22.847666Z","steps":["trace[1635451846] 'process raft request' (duration: 680.854741ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:22.847728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:22.166641Z","time spent":"681.042716ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:36:22.847815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.391287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-01-26T15:36:22.847832Z","caller":"traceutil/trace.go:171","msg":"trace[1265946130] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:928; }","duration":"604.429388ms","start":"2026-01-26T15:36:22.243397Z","end":"2026-01-26T15:36:22.847827Z","steps":["trace[1265946130] 'agreement among raft nodes before linearized reading' (duration: 604.396277ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:22.847844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:22.243384Z","time spent":"604.457348ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-01-26T15:36:22.847979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.569239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-26T15:36:22.847995Z","caller":"traceutil/trace.go:171","msg":"trace[510508811] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:928; }","duration":"536.61757ms","start":"2026-01-26T15:36:22.311371Z","end":"2026-01-26T15:36:22.847989Z","steps":["trace[510508811] 'agreement among raft nodes before linearized reading' (duration: 536.575209ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:22.848018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:22.311354Z","time spent":"536.660682ms","remote":"127.0.0.1:40458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-01-26T15:36:22.848139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.061366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-26T15:36:22.848153Z","caller":"traceutil/trace.go:171","msg":"trace[1998701746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:928; }","duration":"292.285422ms","start":"2026-01-26T15:36:22.555864Z","end":"2026-01-26T15:36:22.848149Z","steps":["trace[1998701746] 'agreement among raft nodes before linearized reading' (duration: 292.21478ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:22.848224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.84976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:22.848234Z","caller":"traceutil/trace.go:171","msg":"trace[1037249295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"306.896851ms","start":"2026-01-26T15:36:22.541334Z","end":"2026-01-26T15:36:22.848231Z","steps":["trace[1037249295] 'agreement among raft nodes before linearized reading' (duration: 306.87901ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:22.848245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:22.541315Z","time spent":"306.926811ms","remote":"127.0.0.1:39318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-26T15:36:34.390161Z","caller":"traceutil/trace.go:171","msg":"trace[1915467461] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"104.611148ms","start":"2026-01-26T15:36:34.285528Z","end":"2026-01-26T15:36:34.39014Z","steps":["trace[1915467461] 'process raft request' (duration: 104.489055ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:37.016445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.554247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-26T15:36:37.016516Z","caller":"traceutil/trace.go:171","msg":"trace[58769954] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"326.407969ms","start":"2026-01-26T15:36:36.690096Z","end":"2026-01-26T15:36:37.016504Z","steps":["trace[58769954] 'read index received' (duration: 33.851µs)","trace[58769954] 'applied index is now lower than readState.Index' (duration: 326.373168ms)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:36:37.01665Z","caller":"traceutil/trace.go:171","msg":"trace[736271615] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"796.247354ms","start":"2026-01-26T15:36:36.220391Z","end":"2026-01-26T15:36:37.016638Z","steps":["trace[736271615] 'process raft request' (duration: 97.42374ms)","trace[736271615] 'compare' (duration: 698.24104ms)"],"step_count":2} {"level":"warn","ts":"2026-01-26T15:36:37.016694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:36.220374Z","time spent":"796.300295ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:36:37.016895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.798008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:37.016921Z","caller":"traceutil/trace.go:171","msg":"trace[2080455098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"326.858939ms","start":"2026-01-26T15:36:36.690055Z","end":"2026-01-26T15:36:37.016914Z","steps":["trace[2080455098] 'agreement among raft nodes before linearized reading' (duration: 326.817488ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:37.016941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:36.690037Z","time spent":"326.89987ms","remote":"127.0.0.1:39318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-26T15:36:37.71529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408002349486570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-26T15:36:38.215748Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408002349486570,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-26T15:36:38.253345Z","caller":"traceutil/trace.go:171","msg":"trace[1325424118] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"1.229550313s","start":"2026-01-26T15:36:37.023766Z","end":"2026-01-26T15:36:38.253316Z","steps":["trace[1325424118] 'process raft request' (duration: 1.223209615s)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:38.253565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:37.023745Z","time spent":"1.229752388s","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:36:38.254988Z","caller":"traceutil/trace.go:171","msg":"trace[1560981007] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"1.230449634s","start":"2026-01-26T15:36:37.024521Z","end":"2026-01-26T15:36:38.254971Z","steps":["trace[1560981007] 'process raft request' (duration: 1.227676409s)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:38.255114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:37.024505Z","time spent":"1.230532976s","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:36:38.255504Z","caller":"traceutil/trace.go:171","msg":"trace[1513240434] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"1.2307158s","start":"2026-01-26T15:36:37.024778Z","end":"2026-01-26T15:36:38.255494Z","steps":["trace[1513240434] 'process raft request' (duration: 1.227473375s)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:38.255566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:37.024772Z","time spent":"1.230768871s","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-26T15:36:38.256557Z","caller":"traceutil/trace.go:171","msg":"trace[1871758912] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1070; }","duration":"1.041936163s","start":"2026-01-26T15:36:37.214605Z","end":"2026-01-26T15:36:38.256541Z","steps":["trace[1871758912] 'read index received' (duration: 1.032378589s)","trace[1871758912] 'applied index is now lower than readState.Index' (duration: 9.556894ms)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:36:38.25668Z","caller":"traceutil/trace.go:171","msg":"trace[1088586764] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"589.998486ms","start":"2026-01-26T15:36:37.666675Z","end":"2026-01-26T15:36:38.256673Z","steps":["trace[1088586764] 'process raft request' (duration: 589.719299ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:38.256717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:37.66666Z","time spent":"590.034157ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:36:38.256847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04225548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-01-26T15:36:38.256869Z","caller":"traceutil/trace.go:171","msg":"trace[2985526] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1030; }","duration":"1.0422762s","start":"2026-01-26T15:36:37.214585Z","end":"2026-01-26T15:36:38.256862Z","steps":["trace[2985526] 'agreement among raft nodes before linearized reading' (duration: 1.042199078s)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:38.256886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:37.214568Z","time spent":"1.042314791s","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-26T15:36:38.257053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.92908ms","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-01-26T15:36:38.257073Z","caller":"traceutil/trace.go:171","msg":"trace[1364839627] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1030; }","duration":"955.978111ms","start":"2026-01-26T15:36:37.301089Z","end":"2026-01-26T15:36:38.257067Z","steps":["trace[1364839627] 'agreement among raft nodes before linearized reading' (duration: 955.865458ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:38.257101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:37.301077Z","time spent":"956.018622ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-01-26T15:36:38.257254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.913443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:36:38.257274Z","caller":"traceutil/trace.go:171","msg":"trace[1296553116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"621.933034ms","start":"2026-01-26T15:36:37.635335Z","end":"2026-01-26T15:36:38.257268Z","steps":["trace[1296553116] 'agreement among raft nodes before linearized reading' (duration: 621.904383ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:38.257288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:37.635282Z","time spent":"622.003715ms","remote":"127.0.0.1:39330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-26T15:36:38.2574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.89046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-01-26T15:36:38.257417Z","caller":"traceutil/trace.go:171","msg":"trace[1265317209] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1030; }","duration":"876.930531ms","start":"2026-01-26T15:36:37.380481Z","end":"2026-01-26T15:36:38.257412Z","steps":["trace[1265317209] 'agreement among raft nodes before linearized reading' (duration: 876.87416ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:38.257432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:37.380467Z","time spent":"876.960932ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-26T15:36:38.257524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.373663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-26T15:36:38.25754Z","caller":"traceutil/trace.go:171","msg":"trace[958600661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1030; }","duration":"901.389833ms","start":"2026-01-26T15:36:37.356146Z","end":"2026-01-26T15:36:38.257536Z","steps":["trace[958600661] 'agreement among raft nodes before linearized reading' (duration: 901.345892ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:36:38.257556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:36:37.356114Z","time spent":"901.437854ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-26T15:39:42.267208Z","caller":"traceutil/trace.go:171","msg":"trace[1689229411] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"126.369329ms","start":"2026-01-26T15:39:42.14081Z","end":"2026-01-26T15:39:42.267179Z","steps":["trace[1689229411] 'process raft request' (duration: 49.803387ms)","trace[1689229411] 'compare' (duration: 76.46346ms)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:39:51.629066Z","caller":"traceutil/trace.go:171","msg":"trace[1545116371] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"162.518862ms","start":"2026-01-26T15:39:51.466524Z","end":"2026-01-26T15:39:51.629043Z","steps":["trace[1545116371] 'read index received' (duration: 162.350008ms)","trace[1545116371] 'applied index is now lower than readState.Index' (duration: 167.844µs)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:39:51.629487Z","caller":"traceutil/trace.go:171","msg":"trace[1112076021] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"430.51101ms","start":"2026-01-26T15:39:51.19896Z","end":"2026-01-26T15:39:51.62947Z","steps":["trace[1112076021] 'process raft request' (duration: 429.975258ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:39:51.629804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-26T15:39:51.198947Z","time spent":"430.578172ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-26T15:39:51.630314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.75492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-26T15:39:51.630359Z","caller":"traceutil/trace.go:171","msg":"trace[707209780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1848; }","duration":"163.865373ms","start":"2026-01-26T15:39:51.466483Z","end":"2026-01-26T15:39:51.630348Z","steps":["trace[707209780] 'agreement among raft nodes before linearized reading' (duration: 163.615277ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:39:51.630804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.758448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-26T15:39:51.630844Z","caller":"traceutil/trace.go:171","msg":"trace[70515669] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1848; }","duration":"149.804329ms","start":"2026-01-26T15:39:51.48103Z","end":"2026-01-26T15:39:51.630835Z","steps":["trace[70515669] 'agreement among raft nodes before linearized reading' (duration: 149.739577ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:39:51.759549Z","caller":"traceutil/trace.go:171","msg":"trace[1751963154] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"123.978614ms","start":"2026-01-26T15:39:51.635555Z","end":"2026-01-26T15:39:51.759533Z","steps":["trace[1751963154] 'process raft request' (duration: 123.889362ms)"],"step_count":1} {"level":"info","ts":"2026-01-26T15:39:51.765515Z","caller":"traceutil/trace.go:171","msg":"trace[1722277579] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"113.712598ms","start":"2026-01-26T15:39:51.651792Z","end":"2026-01-26T15:39:51.765505Z","steps":["trace[1722277579] 'read index received' (duration: 108.067068ms)","trace[1722277579] 'applied index is now lower than readState.Index' (duration: 5.6449ms)"],"step_count":2} {"level":"info","ts":"2026-01-26T15:39:51.765624Z","caller":"traceutil/trace.go:171","msg":"trace[160409284] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"128.682293ms","start":"2026-01-26T15:39:51.636936Z","end":"2026-01-26T15:39:51.765619Z","steps":["trace[160409284] 'process raft request' (duration: 128.519779ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:39:51.765749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.946513ms","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-01-26T15:39:51.765766Z","caller":"traceutil/trace.go:171","msg":"trace[523695034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1850; }","duration":"113.996575ms","start":"2026-01-26T15:39:51.651763Z","end":"2026-01-26T15:39:51.76576Z","steps":["trace[523695034] 'agreement among raft nodes before linearized reading' (duration: 113.925543ms)"],"step_count":1} {"level":"warn","ts":"2026-01-26T15:39:51.765845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.779918ms","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-01-26T15:39:51.765857Z","caller":"traceutil/trace.go:171","msg":"trace[426437804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1850; }","duration":"109.806358ms","start":"2026-01-26T15:39:51.656047Z","end":"2026-01-26T15:39:51.765853Z","steps":["trace[426437804] 'agreement among raft nodes before linearized reading' (duration: 109.773077ms)"],"step_count":1}